I: using fakeroot in build. I: pbuilder: network access will be disabled during build I: Current time: samedi 22 août 2015, 09:50:49 (UTC+1400) I: pbuilder-time-stamp: 1440186649 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: creating local configuration I: copying local configuration I: mounting /proc filesystem I: mounting /run/shm filesystem I: mounting /dev/pts filesystem I: Mounting /dev/shm I: Mounting /sys I: policy-rc.d already exists I: Installing the build-deps I: user script /var/cache/pbuilder/build//52620/tmp/hooks/D01_modify_environment starting I: Changing hostname to test build reproducibility I: user script /var/cache/pbuilder/build//52620/tmp/hooks/D01_modify_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy Version: 0.invalid.0 Architecture: amd64 Maintainer: Debian Pbuilder Team Description: Dummy package to satisfy dependencies with aptitude - created by pbuilder This package was created automatically by pbuilder to satisfy the build-dependencies of the package being currently built. Depends: debhelper (>= 9), dh-golang, golang-go, golang-github-armon-go-metrics-dev, golang-github-hashicorp-go-msgpack-dev dpkg-deb: building package 'pbuilder-satisfydepends-dummy' in '/tmp/satisfydepends-aptitude/pbuilder-satisfydepends-dummy.deb'. Selecting previously unselected package pbuilder-satisfydepends-dummy. (Reading database ... 20247 files and directories currently installed.) Preparing to unpack .../pbuilder-satisfydepends-dummy.deb ... Unpacking pbuilder-satisfydepends-dummy (0.invalid.0) ... dpkg: pbuilder-satisfydepends-dummy: dependency problems, but configuring anyway as you requested: pbuilder-satisfydepends-dummy depends on dh-golang; however: Package dh-golang is not installed. pbuilder-satisfydepends-dummy depends on golang-go; however: Package golang-go is not installed. pbuilder-satisfydepends-dummy depends on golang-github-armon-go-metrics-dev; however: Package golang-github-armon-go-metrics-dev is not installed. pbuilder-satisfydepends-dummy depends on golang-github-hashicorp-go-msgpack-dev; however: Package golang-github-hashicorp-go-msgpack-dev is not installed. Setting up pbuilder-satisfydepends-dummy (0.invalid.0) ... Reading package lists... Building dependency tree... Reading state information... Initializing package states... Writing extended state information... Building tag database... The following NEW packages will be installed: dh-golang{a} golang-check.v1-dev{a} golang-github-armon-go-metrics-dev{a} golang-github-hashicorp-go-msgpack-dev{a} golang-github-ugorji-go-codec-dev{a} golang-github-ugorji-go-msgpack-dev{a} golang-go{a} golang-go-linux-amd64{a} golang-gopkg-mgo.v2-dev{a} golang-gopkg-tomb.v2-dev{a} golang-gopkg-vmihailenco-msgpack.v2-dev{a} golang-goprotobuf-dev{a} golang-procfs-dev{a} golang-prometheus-client-dev{a} golang-protobuf-extensions-dev{a} golang-src{a} init-system-helpers{a} libboost-filesystem1.58.0{a} libboost-program-options1.58.0{a} libboost-system1.58.0{a} libboost-thread1.58.0{a} libexpat1{a} libgoogle-perftools4{a} libpcap0.8{a} libpcrecpp0v5{a} libprotobuf9v5{a} libprotoc9v5{a} libpython-stdlib{a} libpython2.7-minimal{a} libpython2.7-stdlib{a} libsasl2-2{a} libsasl2-dev{a} libsasl2-modules-db{a} libsnappy1v5{a} libssl1.0.0{a} libtcmalloc-minimal4{a} libunwind8{a} libv8-3.14.5{a} mime-support{a} mongodb-clients{a} mongodb-server{a} protobuf-compiler{a} python{a} python-meld3{a} python-minimal{a} python-pkg-resources{a} python2.7{a} python2.7-minimal{a} supervisor{a} 0 packages upgraded, 49 newly installed, 0 to remove and 0 not upgraded. Need to get 74.1 MB of archives. After unpacking 350 MB will be used. Writing extended state information... Get: 1 http://ftp.de.debian.org/debian/ unstable/main libsasl2-modules-db amd64 2.1.26.dfsg1-13 [67.0 kB] Get: 2 http://ftp.de.debian.org/debian/ unstable/main libsasl2-2 amd64 2.1.26.dfsg1-13 [104 kB] Get: 3 http://ftp.de.debian.org/debian/ unstable/main libpython2.7-minimal amd64 2.7.10-3 [382 kB] Get: 4 http://ftp.de.debian.org/debian/ unstable/main python2.7-minimal amd64 2.7.10-3 [1'418 kB] Get: 5 http://ftp.de.debian.org/debian/ unstable/main python-minimal amd64 2.7.9-1 [40.3 kB] Get: 6 http://ftp.de.debian.org/debian/ unstable/main mime-support all 3.59 [36.4 kB] Get: 7 http://ftp.de.debian.org/debian/ unstable/main libexpat1 amd64 2.1.0-7 [80.0 kB] Get: 8 http://ftp.de.debian.org/debian/ unstable/main libssl1.0.0 amd64 1.0.2d-1 [1'274 kB] Get: 9 http://ftp.de.debian.org/debian/ unstable/main libpython2.7-stdlib amd64 2.7.10-3 [1'854 kB] Get: 10 http://ftp.de.debian.org/debian/ unstable/main python2.7 amd64 2.7.10-3 [258 kB] Get: 11 http://ftp.de.debian.org/debian/ unstable/main libpython-stdlib amd64 2.7.9-1 [19.5 kB] Get: 12 http://ftp.de.debian.org/debian/ unstable/main python amd64 2.7.9-1 [151 kB] Get: 13 http://ftp.de.debian.org/debian/ unstable/main libpcrecpp0v5 amd64 2:8.35-7.1 [143 kB] Get: 14 http://ftp.de.debian.org/debian/ unstable/main libunwind8 amd64 1.1-4 [50.8 kB] Get: 15 http://ftp.de.debian.org/debian/ unstable/main libboost-system1.58.0 amd64 1.58.0+dfsg-3 [30.6 kB] Get: 16 http://ftp.de.debian.org/debian/ unstable/main libboost-filesystem1.58.0 amd64 1.58.0+dfsg-3 [60.1 kB] Get: 17 http://ftp.de.debian.org/debian/ unstable/main libboost-program-options1.58.0 amd64 1.58.0+dfsg-3 [157 kB] Get: 18 http://ftp.de.debian.org/debian/ unstable/main libboost-thread1.58.0 amd64 1.58.0+dfsg-3 [69.6 kB] Get: 19 http://ftp.de.debian.org/debian/ unstable/main libtcmalloc-minimal4 amd64 2.2.1-0.2 [128 kB] Get: 20 http://ftp.de.debian.org/debian/ unstable/main libgoogle-perftools4 amd64 2.2.1-0.2 [304 kB] Get: 21 http://ftp.de.debian.org/debian/ unstable/main libpcap0.8 amd64 1.7.4-1 [136 kB] Get: 22 http://ftp.de.debian.org/debian/ unstable/main libsnappy1v5 amd64 1.1.3-2 [50.9 kB] Get: 23 http://ftp.de.debian.org/debian/ unstable/main libv8-3.14.5 amd64 3.14.5.8-10 [1'254 kB] Get: 24 http://ftp.de.debian.org/debian/ unstable/main mongodb-clients amd64 1:2.4.14-3 [33.5 MB] Get: 25 http://ftp.de.debian.org/debian/ unstable/main init-system-helpers all 1.23 [15.2 kB] Get: 26 http://ftp.de.debian.org/debian/ unstable/main mongodb-server amd64 1:2.4.14-3 [4'223 kB] Get: 27 http://ftp.de.debian.org/debian/ unstable/main golang-src amd64 2:1.4.2-3 [5'404 kB] Get: 28 http://ftp.de.debian.org/debian/ unstable/main golang-go-linux-amd64 amd64 2:1.4.2-3 [8'696 kB] Get: 29 http://ftp.de.debian.org/debian/ unstable/main golang-go amd64 2:1.4.2-3 [11.5 MB] Get: 30 http://ftp.de.debian.org/debian/ unstable/main libprotobuf9v5 amd64 2.6.1-1.2 [344 kB] Get: 31 http://ftp.de.debian.org/debian/ unstable/main libprotoc9v5 amd64 2.6.1-1.2 [289 kB] Get: 32 http://ftp.de.debian.org/debian/ unstable/main libsasl2-dev amd64 2.1.26.dfsg1-13 [310 kB] Get: 33 http://ftp.de.debian.org/debian/ unstable/main protobuf-compiler amd64 2.6.1-1.2 [36.0 kB] Get: 34 http://ftp.de.debian.org/debian/ unstable/main python-pkg-resources all 18.0.1-2 [73.6 kB] Get: 35 http://ftp.de.debian.org/debian/ unstable/main dh-golang all 1.12 [9'402 B] Get: 36 http://ftp.de.debian.org/debian/ unstable/main golang-check.v1-dev all 0.0+git20150729.11d3bc7-1 [28.9 kB] Get: 37 http://ftp.de.debian.org/debian/ unstable/main golang-goprotobuf-dev amd64 0.0~git20150526-2 [779 kB] Get: 38 http://ftp.de.debian.org/debian/ unstable/main golang-protobuf-extensions-dev all 0+git20150513.fc2b8d3-3 [8'654 B] Get: 39 http://ftp.de.debian.org/debian/ unstable/main golang-procfs-dev all 0+git20150616.c91d8ee-1 [13.5 kB] Get: 40 http://ftp.de.debian.org/debian/ unstable/main golang-prometheus-client-dev all 0.6.0+ds-1 [80.5 kB] Get: 41 http://ftp.de.debian.org/debian/ unstable/main golang-github-armon-go-metrics-dev all 0.0~git20150601-1 [11.2 kB] Get: 42 http://ftp.de.debian.org/debian/ unstable/main golang-github-ugorji-go-msgpack-dev all 0.0~git20130605.792643-1 [20.3 kB] Get: 43 http://ftp.de.debian.org/debian/ unstable/main golang-github-ugorji-go-codec-dev all 0.0~git20150815.821cda7e-1 [106 kB] Get: 44 http://ftp.de.debian.org/debian/ unstable/main golang-gopkg-vmihailenco-msgpack.v2-dev all 2.3-1 [14.9 kB] Get: 45 http://ftp.de.debian.org/debian/ unstable/main golang-gopkg-tomb.v2-dev all 0.0~git20140626.14b3d72-1 [5'140 B] Get: 46 http://ftp.de.debian.org/debian/ unstable/main python-meld3 amd64 1.0.2-1 [37.2 kB] Get: 47 http://ftp.de.debian.org/debian/ unstable/main supervisor all 3.0r1-1 [267 kB] Get: 48 http://ftp.de.debian.org/debian/ unstable/main golang-gopkg-mgo.v2-dev all 2015.06.03-2 [129 kB] Get: 49 http://ftp.de.debian.org/debian/ unstable/main golang-github-hashicorp-go-msgpack-dev all 0.0~git20150518-1 [42.1 kB] Fetched 74.1 MB in 3s (24.3 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libsasl2-modules-db:amd64. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 20247 files and directories currently installed.) Preparing to unpack .../libsasl2-modules-db_2.1.26.dfsg1-13_amd64.deb ... Unpacking libsasl2-modules-db:amd64 (2.1.26.dfsg1-13) ... Selecting previously unselected package libsasl2-2:amd64. Preparing to unpack .../libsasl2-2_2.1.26.dfsg1-13_amd64.deb ... Unpacking libsasl2-2:amd64 (2.1.26.dfsg1-13) ... Selecting previously unselected package libpython2.7-minimal:amd64. Preparing to unpack .../libpython2.7-minimal_2.7.10-3_amd64.deb ... Unpacking libpython2.7-minimal:amd64 (2.7.10-3) ... Selecting previously unselected package python2.7-minimal. Preparing to unpack .../python2.7-minimal_2.7.10-3_amd64.deb ... Unpacking python2.7-minimal (2.7.10-3) ... Selecting previously unselected package python-minimal. Preparing to unpack .../python-minimal_2.7.9-1_amd64.deb ... Unpacking python-minimal (2.7.9-1) ... Selecting previously unselected package mime-support. Preparing to unpack .../mime-support_3.59_all.deb ... Unpacking mime-support (3.59) ... Selecting previously unselected package libexpat1:amd64. Preparing to unpack .../libexpat1_2.1.0-7_amd64.deb ... Unpacking libexpat1:amd64 (2.1.0-7) ... Selecting previously unselected package libssl1.0.0:amd64. Preparing to unpack .../libssl1.0.0_1.0.2d-1_amd64.deb ... Unpacking libssl1.0.0:amd64 (1.0.2d-1) ... Selecting previously unselected package libpython2.7-stdlib:amd64. Preparing to unpack .../libpython2.7-stdlib_2.7.10-3_amd64.deb ... Unpacking libpython2.7-stdlib:amd64 (2.7.10-3) ... Selecting previously unselected package python2.7. Preparing to unpack .../python2.7_2.7.10-3_amd64.deb ... Unpacking python2.7 (2.7.10-3) ... Selecting previously unselected package libpython-stdlib:amd64. Preparing to unpack .../libpython-stdlib_2.7.9-1_amd64.deb ... Unpacking libpython-stdlib:amd64 (2.7.9-1) ... Processing triggers for man-db (2.7.2-1) ... Setting up libpython2.7-minimal:amd64 (2.7.10-3) ... Setting up python2.7-minimal (2.7.10-3) ... Setting up python-minimal (2.7.9-1) ... Selecting previously unselected package python. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 21068 files and directories currently installed.) Preparing to unpack .../python_2.7.9-1_amd64.deb ... Unpacking python (2.7.9-1) ... Selecting previously unselected package libpcrecpp0v5:amd64. Preparing to unpack .../libpcrecpp0v5_2%3a8.35-7.1_amd64.deb ... Unpacking libpcrecpp0v5:amd64 (2:8.35-7.1) ... Selecting previously unselected package libunwind8. Preparing to unpack .../libunwind8_1.1-4_amd64.deb ... Unpacking libunwind8 (1.1-4) ... Selecting previously unselected package libboost-system1.58.0:amd64. Preparing to unpack .../libboost-system1.58.0_1.58.0+dfsg-3_amd64.deb ... Unpacking libboost-system1.58.0:amd64 (1.58.0+dfsg-3) ... Selecting previously unselected package libboost-filesystem1.58.0:amd64. Preparing to unpack .../libboost-filesystem1.58.0_1.58.0+dfsg-3_amd64.deb ... Unpacking libboost-filesystem1.58.0:amd64 (1.58.0+dfsg-3) ... Selecting previously unselected package libboost-program-options1.58.0:amd64. Preparing to unpack .../libboost-program-options1.58.0_1.58.0+dfsg-3_amd64.deb ... Unpacking libboost-program-options1.58.0:amd64 (1.58.0+dfsg-3) ... Selecting previously unselected package libboost-thread1.58.0:amd64. Preparing to unpack .../libboost-thread1.58.0_1.58.0+dfsg-3_amd64.deb ... Unpacking libboost-thread1.58.0:amd64 (1.58.0+dfsg-3) ... Selecting previously unselected package libtcmalloc-minimal4. Preparing to unpack .../libtcmalloc-minimal4_2.2.1-0.2_amd64.deb ... Unpacking libtcmalloc-minimal4 (2.2.1-0.2) ... Selecting previously unselected package libgoogle-perftools4. Preparing to unpack .../libgoogle-perftools4_2.2.1-0.2_amd64.deb ... Unpacking libgoogle-perftools4 (2.2.1-0.2) ... Selecting previously unselected package libpcap0.8:amd64. Preparing to unpack .../libpcap0.8_1.7.4-1_amd64.deb ... Unpacking libpcap0.8:amd64 (1.7.4-1) ... Selecting previously unselected package libsnappy1v5:amd64. Preparing to unpack .../libsnappy1v5_1.1.3-2_amd64.deb ... Unpacking libsnappy1v5:amd64 (1.1.3-2) ... Selecting previously unselected package libv8-3.14.5. Preparing to unpack .../libv8-3.14.5_3.14.5.8-10_amd64.deb ... Unpacking libv8-3.14.5 (3.14.5.8-10) ... Selecting previously unselected package mongodb-clients. Preparing to unpack .../mongodb-clients_1%3a2.4.14-3_amd64.deb ... Unpacking mongodb-clients (1:2.4.14-3) ... Selecting previously unselected package init-system-helpers. Preparing to unpack .../init-system-helpers_1.23_all.deb ... Unpacking init-system-helpers (1.23) ... Selecting previously unselected package mongodb-server. Preparing to unpack .../mongodb-server_1%3a2.4.14-3_amd64.deb ... Unpacking mongodb-server (1:2.4.14-3) ... Selecting previously unselected package golang-src. Preparing to unpack .../golang-src_2%3a1.4.2-3_amd64.deb ... Unpacking golang-src (2:1.4.2-3) ... Selecting previously unselected package golang-go-linux-amd64. Preparing to unpack .../golang-go-linux-amd64_2%3a1.4.2-3_amd64.deb ... Unpacking golang-go-linux-amd64 (2:1.4.2-3) ... Selecting previously unselected package golang-go. Preparing to unpack .../golang-go_2%3a1.4.2-3_amd64.deb ... Unpacking golang-go (2:1.4.2-3) ... Selecting previously unselected package libprotobuf9v5:amd64. Preparing to unpack .../libprotobuf9v5_2.6.1-1.2_amd64.deb ... Unpacking libprotobuf9v5:amd64 (2.6.1-1.2) ... Selecting previously unselected package libprotoc9v5:amd64. Preparing to unpack .../libprotoc9v5_2.6.1-1.2_amd64.deb ... Unpacking libprotoc9v5:amd64 (2.6.1-1.2) ... Selecting previously unselected package libsasl2-dev. Preparing to unpack .../libsasl2-dev_2.1.26.dfsg1-13_amd64.deb ... Unpacking libsasl2-dev (2.1.26.dfsg1-13) ... Selecting previously unselected package protobuf-compiler. Preparing to unpack .../protobuf-compiler_2.6.1-1.2_amd64.deb ... Unpacking protobuf-compiler (2.6.1-1.2) ... Selecting previously unselected package python-pkg-resources. Preparing to unpack .../python-pkg-resources_18.0.1-2_all.deb ... Unpacking python-pkg-resources (18.0.1-2) ... Selecting previously unselected package dh-golang. Preparing to unpack .../dh-golang_1.12_all.deb ... Unpacking dh-golang (1.12) ... Selecting previously unselected package golang-check.v1-dev. Preparing to unpack .../golang-check.v1-dev_0.0+git20150729.11d3bc7-1_all.deb ... Unpacking golang-check.v1-dev (0.0+git20150729.11d3bc7-1) ... Selecting previously unselected package golang-goprotobuf-dev. Preparing to unpack .../golang-goprotobuf-dev_0.0~git20150526-2_amd64.deb ... Unpacking golang-goprotobuf-dev (0.0~git20150526-2) ... Selecting previously unselected package golang-protobuf-extensions-dev. Preparing to unpack .../golang-protobuf-extensions-dev_0+git20150513.fc2b8d3-3_all.deb ... Unpacking golang-protobuf-extensions-dev (0+git20150513.fc2b8d3-3) ... Selecting previously unselected package golang-procfs-dev. Preparing to unpack .../golang-procfs-dev_0+git20150616.c91d8ee-1_all.deb ... Unpacking golang-procfs-dev (0+git20150616.c91d8ee-1) ... Selecting previously unselected package golang-prometheus-client-dev. Preparing to unpack .../golang-prometheus-client-dev_0.6.0+ds-1_all.deb ... Unpacking golang-prometheus-client-dev (0.6.0+ds-1) ... Selecting previously unselected package golang-github-armon-go-metrics-dev. Preparing to unpack .../golang-github-armon-go-metrics-dev_0.0~git20150601-1_all.deb ... Unpacking golang-github-armon-go-metrics-dev (0.0~git20150601-1) ... Selecting previously unselected package golang-github-ugorji-go-msgpack-dev. Preparing to unpack .../golang-github-ugorji-go-msgpack-dev_0.0~git20130605.792643-1_all.deb ... Unpacking golang-github-ugorji-go-msgpack-dev (0.0~git20130605.792643-1) ... Selecting previously unselected package golang-github-ugorji-go-codec-dev. Preparing to unpack .../golang-github-ugorji-go-codec-dev_0.0~git20150815.821cda7e-1_all.deb ... Unpacking golang-github-ugorji-go-codec-dev (0.0~git20150815.821cda7e-1) ... Selecting previously unselected package golang-gopkg-vmihailenco-msgpack.v2-dev. Preparing to unpack .../golang-gopkg-vmihailenco-msgpack.v2-dev_2.3-1_all.deb ... Unpacking golang-gopkg-vmihailenco-msgpack.v2-dev (2.3-1) ... Selecting previously unselected package golang-gopkg-tomb.v2-dev. Preparing to unpack .../golang-gopkg-tomb.v2-dev_0.0~git20140626.14b3d72-1_all.deb ... Unpacking golang-gopkg-tomb.v2-dev (0.0~git20140626.14b3d72-1) ... Selecting previously unselected package python-meld3. Preparing to unpack .../python-meld3_1.0.2-1_amd64.deb ... Unpacking python-meld3 (1.0.2-1) ... Selecting previously unselected package supervisor. Preparing to unpack .../supervisor_3.0r1-1_all.deb ... Unpacking supervisor (3.0r1-1) ... Selecting previously unselected package golang-gopkg-mgo.v2-dev. Preparing to unpack .../golang-gopkg-mgo.v2-dev_2015.06.03-2_all.deb ... Unpacking golang-gopkg-mgo.v2-dev (2015.06.03-2) ... Selecting previously unselected package golang-github-hashicorp-go-msgpack-dev. Preparing to unpack .../golang-github-hashicorp-go-msgpack-dev_0.0~git20150518-1_all.deb ... Unpacking golang-github-hashicorp-go-msgpack-dev (0.0~git20150518-1) ... Processing triggers for man-db (2.7.2-1) ... Processing triggers for systemd (224-1) ... Setting up libsasl2-modules-db:amd64 (2.1.26.dfsg1-13) ... Setting up libsasl2-2:amd64 (2.1.26.dfsg1-13) ... Setting up mime-support (3.59) ... Setting up libexpat1:amd64 (2.1.0-7) ... Setting up libssl1.0.0:amd64 (1.0.2d-1) ... Setting up libpython2.7-stdlib:amd64 (2.7.10-3) ... Setting up python2.7 (2.7.10-3) ... Setting up libpython-stdlib:amd64 (2.7.9-1) ... Setting up python (2.7.9-1) ... Setting up libpcrecpp0v5:amd64 (2:8.35-7.1) ... Setting up libunwind8 (1.1-4) ... Setting up libboost-system1.58.0:amd64 (1.58.0+dfsg-3) ... Setting up libboost-filesystem1.58.0:amd64 (1.58.0+dfsg-3) ... Setting up libboost-program-options1.58.0:amd64 (1.58.0+dfsg-3) ... Setting up libboost-thread1.58.0:amd64 (1.58.0+dfsg-3) ... Setting up libtcmalloc-minimal4 (2.2.1-0.2) ... Setting up libgoogle-perftools4 (2.2.1-0.2) ... Setting up libpcap0.8:amd64 (1.7.4-1) ... Setting up libsnappy1v5:amd64 (1.1.3-2) ... Setting up libv8-3.14.5 (3.14.5.8-10) ... Setting up mongodb-clients (1:2.4.14-3) ... Setting up init-system-helpers (1.23) ... Setting up mongodb-server (1:2.4.14-3) ... Adding system user `mongodb' (UID 104) ... Adding new user `mongodb' (UID 104) with group `nogroup' ... Not creating home directory `/var/lib/mongodb'. Adding group `mongodb' (GID 108) ... Done. Adding user `mongodb' to group `mongodb' ... Adding user mongodb to group mongodb Done. invoke-rc.d: policy-rc.d denied execution of start. Setting up golang-src (2:1.4.2-3) ... Setting up golang-go-linux-amd64 (2:1.4.2-3) ... Setting up golang-go (2:1.4.2-3) ... update-alternatives: using /usr/lib/go/bin/go to provide /usr/bin/go (go) in auto mode Setting up libprotobuf9v5:amd64 (2.6.1-1.2) ... Setting up libprotoc9v5:amd64 (2.6.1-1.2) ... Setting up libsasl2-dev (2.1.26.dfsg1-13) ... Setting up protobuf-compiler (2.6.1-1.2) ... Setting up python-pkg-resources (18.0.1-2) ... Setting up dh-golang (1.12) ... Setting up golang-check.v1-dev (0.0+git20150729.11d3bc7-1) ... Setting up golang-goprotobuf-dev (0.0~git20150526-2) ... Setting up golang-protobuf-extensions-dev (0+git20150513.fc2b8d3-3) ... Setting up golang-procfs-dev (0+git20150616.c91d8ee-1) ... Setting up golang-prometheus-client-dev (0.6.0+ds-1) ... Setting up golang-github-armon-go-metrics-dev (0.0~git20150601-1) ... Setting up golang-github-ugorji-go-msgpack-dev (0.0~git20130605.792643-1) ... Setting up golang-github-ugorji-go-codec-dev (0.0~git20150815.821cda7e-1) ... Setting up golang-gopkg-vmihailenco-msgpack.v2-dev (2.3-1) ... Setting up golang-gopkg-tomb.v2-dev (0.0~git20140626.14b3d72-1) ... Setting up python-meld3 (1.0.2-1) ... Setting up supervisor (3.0r1-1) ... invoke-rc.d: policy-rc.d denied execution of start. Setting up golang-gopkg-mgo.v2-dev (2015.06.03-2) ... Setting up golang-github-hashicorp-go-msgpack-dev (0.0~git20150518-1) ... Processing triggers for libc-bin (2.19-19) ... Processing triggers for systemd (224-1) ... Reading package lists... Building dependency tree... Reading state information... Reading extended state information... Initializing package states... Writing extended state information... Building tag database... -> Finished parsing the build-deps Reading package lists... Building dependency tree... Reading state information... fakeroot is already the newest version. 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Copying source file I: copying [golang-github-hashicorp-raft_0.0~git20150728.9b586e2-1.dsc] I: copying [./golang-github-hashicorp-raft_0.0~git20150728.9b586e2.orig.tar.gz] I: copying [./golang-github-hashicorp-raft_0.0~git20150728.9b586e2-1.debian.tar.xz] I: Extracting source gpgv: keyblock resource `/tmp/buildd/.gnupg/trustedkeys.gpg': file open error gpgv: Signature made lun 03 aoû 2015 10:46:58 GMT-14 using RSA key ID 4AC8EE1D gpgv: Can't check signature: public key not found dpkg-source: warning: failed to verify signature on ./golang-github-hashicorp-raft_0.0~git20150728.9b586e2-1.dsc dpkg-source: info: extracting golang-github-hashicorp-raft in golang-github-hashicorp-raft-0.0~git20150728.9b586e2 dpkg-source: info: unpacking golang-github-hashicorp-raft_0.0~git20150728.9b586e2.orig.tar.gz dpkg-source: info: unpacking golang-github-hashicorp-raft_0.0~git20150728.9b586e2-1.debian.tar.xz dpkg-source: info: applying disable-unstable-tests.patch I: Building the package W: no hooks of type A found -- ignoring I: Running cd tmp/buildd/*/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" dpkg-buildpackage -us -uc -b -rfakeroot dpkg-buildpackage: source package golang-github-hashicorp-raft dpkg-buildpackage: source version 0.0~git20150728.9b586e2-1 dpkg-buildpackage: source distribution unstable dpkg-buildpackage: source changed by Alexandre Viau dpkg-source --before-build golang-github-hashicorp-raft-0.0~git20150728.9b586e2 dpkg-buildpackage: host architecture amd64 fakeroot debian/rules clean dh clean --buildsystem=golang --with=golang dh_testdir -O--buildsystem=golang dh_auto_clean -O--buildsystem=golang dh_clean -O--buildsystem=golang debian/rules build dh build --buildsystem=golang --with=golang dh_testdir -O--buildsystem=golang dh_auto_configure -O--buildsystem=golang dh_auto_build -O--buildsystem=golang go install -v github.com/hashicorp/raft github.com/hashicorp/raft/bench github.com/armon/go-metrics github.com/hashicorp/go-msgpack/codec github.com/hashicorp/raft github.com/hashicorp/raft/bench dh_auto_test -O--buildsystem=golang go test -v github.com/hashicorp/raft github.com/hashicorp/raft/bench === RUN TestDiscardSnapshotStoreImpl --- PASS: TestDiscardSnapshotStoreImpl (0.00s) === RUN TestDiscardSnapshotSinkImpl --- PASS: TestDiscardSnapshotSinkImpl (0.00s) === RUN TestFileSnapshotStoreImpl --- PASS: TestFileSnapshotStoreImpl (0.00s) === RUN TestFileSnapshotSinkImpl --- PASS: TestFileSnapshotSinkImpl (0.00s) === RUN TestFileSS_CreateSnapshotMissingParentDir 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft246944061/raft861444472/snapshots/3-10-1440186742255.tmp --- PASS: TestFileSS_CreateSnapshotMissingParentDir (0.00s) === RUN TestFileSS_CreateSnapshot 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft401554551/snapshots/3-10-1440186742255.tmp 2015/08/21 19:52:22 [WARN] snapshot: Found temporary snapshot: 3-10-1440186742255.tmp --- PASS: TestFileSS_CreateSnapshot (0.00s) === RUN TestFileSS_CancelSnapshot 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft281436010/snapshots/3-10-1440186742257.tmp --- PASS: TestFileSS_CancelSnapshot (0.00s) === RUN TestFileSS_Retention 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft284541377/snapshots/3-10-1440186742257.tmp 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft284541377/snapshots/3-11-1440186742257.tmp 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft284541377/snapshots/3-12-1440186742258.tmp 2015/08/21 19:52:22 [INFO] snapshot: reaping snapshot /tmp/raft284541377/snapshots/3-10-1440186742257 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft284541377/snapshots/3-13-1440186742259.tmp 2015/08/21 19:52:22 [INFO] snapshot: reaping snapshot /tmp/raft284541377/snapshots/3-11-1440186742257 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft284541377/snapshots/3-14-1440186742259.tmp 2015/08/21 19:52:22 [INFO] snapshot: reaping snapshot /tmp/raft284541377/snapshots/3-12-1440186742258 --- PASS: TestFileSS_Retention (0.00s) === RUN TestFileSS_BadPerm --- PASS: TestFileSS_BadPerm (0.00s) === RUN TestFileSS_MissingParentDir --- PASS: TestFileSS_MissingParentDir (0.00s) === RUN TestFileSS_Ordering 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft698405920/snapshots/5-130350-1440186742260.tmp 2015/08/21 19:52:22 [INFO] snapshot: Creating new snapshot at /tmp/raft698405920/snapshots/36-204917-1440186742261.tmp --- PASS: TestFileSS_Ordering (0.00s) === RUN TestInflight_StartCommit --- PASS: TestInflight_StartCommit (0.00s) === RUN TestInflight_Cancel --- PASS: TestInflight_Cancel (0.00s) === RUN TestInflight_StartAll --- PASS: TestInflight_StartAll (0.00s) === RUN TestInflight_CommitRange --- PASS: TestInflight_CommitRange (0.00s) === RUN TestInflight_NonContiguous --- PASS: TestInflight_NonContiguous (0.00s) === RUN TestInmemTransportImpl --- PASS: TestInmemTransportImpl (0.00s) === RUN TestRaft_Integ --- SKIP: TestRaft_Integ (0.00s) === RUN TestLogCache --- PASS: TestLogCache (0.00s) === RUN TestNetworkTransport_StartStop --- PASS: TestNetworkTransport_StartStop (0.00s) === RUN TestNetworkTransport_Heartbeat_FastPath 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:47592 accepted connection from: 127.0.0.1:46618 --- PASS: TestNetworkTransport_Heartbeat_FastPath (0.00s) === RUN TestNetworkTransport_AppendEntries 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:41154 accepted connection from: 127.0.0.1:36314 --- PASS: TestNetworkTransport_AppendEntries (0.00s) === RUN TestNetworkTransport_AppendEntriesPipeline 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:39786 accepted connection from: 127.0.0.1:49815 --- PASS: TestNetworkTransport_AppendEntriesPipeline (0.00s) === RUN TestNetworkTransport_RequestVote 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:36950 accepted connection from: 127.0.0.1:37938 --- PASS: TestNetworkTransport_RequestVote (0.00s) === RUN TestNetworkTransport_InstallSnapshot 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:43940 accepted connection from: 127.0.0.1:34381 --- PASS: TestNetworkTransport_InstallSnapshot (0.00s) === RUN TestNetworkTransport_EncodeDecode --- PASS: TestNetworkTransport_EncodeDecode (0.00s) === RUN TestNetworkTransport_PooledConn 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:57817 accepted connection from: 127.0.0.1:41362 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:57817 accepted connection from: 127.0.0.1:41363 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:57817 accepted connection from: 127.0.0.1:41364 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:57817 accepted connection from: 127.0.0.1:41365 2015/08/21 19:52:22 [DEBUG] raft-net: 127.0.0.1:57817 accepted connection from: 127.0.0.1:41366 --- PASS: TestNetworkTransport_PooledConn (0.00s) === RUN TestJSONPeers --- PASS: TestJSONPeers (0.00s) === RUN TestRaft_StartStop 2015/08/21 19:52:22 [WARN] Fully Connecting --- PASS: TestRaft_StartStop (0.01s) === RUN TestRaft_AfterShutdown 2015/08/21 19:52:22 [WARN] Fully Connecting --- PASS: TestRaft_AfterShutdown (0.01s) === RUN TestRaft_SingleNode 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at e92b350c-b85d-509a-7e2e-8dc467b1de55 [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at e92b350c-b85d-509a-7e2e-8dc467b1de55 [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:22 [INFO] raft: Node at e92b350c-b85d-509a-7e2e-8dc467b1de55 [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:22 [DEBUG] raft: Node e92b350c-b85d-509a-7e2e-8dc467b1de55 updated peer set (2): [e92b350c-b85d-509a-7e2e-8dc467b1de55] --- PASS: TestRaft_SingleNode (0.06s) === RUN TestRaft_TripleNode 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at 34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 6f051773-9332-8526-48e5-e8b992a24cb2 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89 [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at 34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Node at 34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 6f051773-9332-8526-48e5-e8b992a24cb2 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89 2015/08/21 19:52:22 [DEBUG] raft: Node 34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd updated peer set (2): [34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd 6f051773-9332-8526-48e5-e8b992a24cb2 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89] 2015/08/21 19:52:22 [DEBUG] raft: Node 6f051773-9332-8526-48e5-e8b992a24cb2 updated peer set (2): [34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd 6f051773-9332-8526-48e5-e8b992a24cb2 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89] 2015/08/21 19:52:22 [DEBUG] raft: Node 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89 updated peer set (2): [34e9eaf8-e96f-4b6a-0b29-7977fb6a19bd 6f051773-9332-8526-48e5-e8b992a24cb2 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89] 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 0289aa22-1978-0ca8-d4a6-eaa2f9b46d89 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 6f051773-9332-8526-48e5-e8b992a24cb2 --- PASS: TestRaft_TripleNode (0.11s) === RUN TestRaft_BehindFollower 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at fe1a99bb-b167-326a-ca26-f1ecaff5d40e [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 69540c2e-0f0d-c921-7bd8-94c49ade2627 [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at fe1a99bb-b167-326a-ca26-f1ecaff5d40e [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Node at fe1a99bb-b167-326a-ca26-f1ecaff5d40e [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 69540c2e-0f0d-c921-7bd8-94c49ade2627 2015/08/21 19:52:22 [DEBUG] raft: Node fe1a99bb-b167-326a-ca26-f1ecaff5d40e updated peer set (2): [fe1a99bb-b167-326a-ca26-f1ecaff5d40e 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 69540c2e-0f0d-c921-7bd8-94c49ade2627] 2015/08/21 19:52:22 [WARN] Disconnecting 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [DEBUG] raft: Node 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 updated peer set (2): [fe1a99bb-b167-326a-ca26-f1ecaff5d40e 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 69540c2e-0f0d-c921-7bd8-94c49ade2627] 2015/08/21 19:52:22 [DEBUG] raft: Node 69540c2e-0f0d-c921-7bd8-94c49ade2627 updated peer set (2): [fe1a99bb-b167-326a-ca26-f1ecaff5d40e 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 69540c2e-0f0d-c921-7bd8-94c49ade2627] 2015/08/21 19:52:22 [ERR] raft: Failed to pipeline AppendEntries to 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8: append pipeline closed 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [ERR] raft: Failed to AppendEntries to 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8: failed to connect to peer: 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [ERR] raft: Failed to heartbeat to 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8: failed to connect to peer: 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [INFO] Finished apply without behind follower 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 324fa80c-72a5-c5e8-de9a-ba1c3d6f58c8 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 69540c2e-0f0d-c921-7bd8-94c49ade2627 2015/08/21 19:52:22 [ERR] raft: Failed to heartbeat to 69540c2e-0f0d-c921-7bd8-94c49ade2627: command timed out --- PASS: TestRaft_BehindFollower (0.15s) === RUN TestRaft_ApplyNonLeader 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 0196ac40-50b0-7f41-7f88-193c2e136231 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 19b30327-8f14-a5a6-9e2a-9d9362a0495e [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at 19b30327-8f14-a5a6-9e2a-9d9362a0495e [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Node at 19b30327-8f14-a5a6-9e2a-9d9362a0495e [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 0196ac40-50b0-7f41-7f88-193c2e136231 2015/08/21 19:52:22 [DEBUG] raft: Node 19b30327-8f14-a5a6-9e2a-9d9362a0495e updated peer set (2): [19b30327-8f14-a5a6-9e2a-9d9362a0495e 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 0196ac40-50b0-7f41-7f88-193c2e136231] 2015/08/21 19:52:22 [DEBUG] raft: Node 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 updated peer set (2): [19b30327-8f14-a5a6-9e2a-9d9362a0495e 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 0196ac40-50b0-7f41-7f88-193c2e136231] 2015/08/21 19:52:22 [DEBUG] raft: Node 0196ac40-50b0-7f41-7f88-193c2e136231 updated peer set (2): [19b30327-8f14-a5a6-9e2a-9d9362a0495e 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 0196ac40-50b0-7f41-7f88-193c2e136231] 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 36d2da1c-e7fc-2224-3ece-27d42a77b8b1 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 0196ac40-50b0-7f41-7f88-193c2e136231 --- PASS: TestRaft_ApplyNonLeader (0.08s) === RUN TestRaft_ApplyConcurrent 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at 313bc4b8-a213-d4f5-9934-5d7bc37314e8 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 10b9c925-4c0a-d061-a06f-c8548b0482f5 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at 748d8c4d-56fe-734d-14a7-0d705e44ed13 [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at 313bc4b8-a213-d4f5-9934-5d7bc37314e8 [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:22 [INFO] raft: Node at 313bc4b8-a213-d4f5-9934-5d7bc37314e8 [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 10b9c925-4c0a-d061-a06f-c8548b0482f5 2015/08/21 19:52:22 [INFO] raft: pipelining replication to peer 748d8c4d-56fe-734d-14a7-0d705e44ed13 2015/08/21 19:52:22 [DEBUG] raft: Node 313bc4b8-a213-d4f5-9934-5d7bc37314e8 updated peer set (2): [313bc4b8-a213-d4f5-9934-5d7bc37314e8 10b9c925-4c0a-d061-a06f-c8548b0482f5 748d8c4d-56fe-734d-14a7-0d705e44ed13] 2015/08/21 19:52:22 [DEBUG] raft: Node 10b9c925-4c0a-d061-a06f-c8548b0482f5 updated peer set (2): [313bc4b8-a213-d4f5-9934-5d7bc37314e8 10b9c925-4c0a-d061-a06f-c8548b0482f5 748d8c4d-56fe-734d-14a7-0d705e44ed13] 2015/08/21 19:52:22 [DEBUG] raft: Node 748d8c4d-56fe-734d-14a7-0d705e44ed13 updated peer set (2): [313bc4b8-a213-d4f5-9934-5d7bc37314e8 10b9c925-4c0a-d061-a06f-c8548b0482f5 748d8c4d-56fe-734d-14a7-0d705e44ed13] 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 10b9c925-4c0a-d061-a06f-c8548b0482f5 2015/08/21 19:52:22 [INFO] raft: aborting pipeline replication to peer 748d8c4d-56fe-734d-14a7-0d705e44ed13 --- PASS: TestRaft_ApplyConcurrent (0.16s) === RUN TestRaft_ApplyConcurrent_Timeout 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at 556734fd-76e4-341c-17a2-667acdc998af [Follower] entering Follower state 2015/08/21 19:52:22 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:22 [INFO] raft: Node at 556734fd-76e4-341c-17a2-667acdc998af [Candidate] entering Candidate state 2015/08/21 19:52:22 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:22 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:22 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:22 [INFO] raft: Node at 556734fd-76e4-341c-17a2-667acdc998af [Leader] entering Leader state 2015/08/21 19:52:22 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:22 [DEBUG] raft: Node 556734fd-76e4-341c-17a2-667acdc998af updated peer set (2): [556734fd-76e4-341c-17a2-667acdc998af] --- FAIL: TestRaft_ApplyConcurrent_Timeout (0.14s) raft_test.go:700: expected a timeout === RUN TestRaft_JoinNode 2015/08/21 19:52:22 [WARN] Fully Connecting 2015/08/21 19:52:22 [INFO] raft: Node at 11579c9e-424d-ca96-4114-2097d7fd5a93 [Follower] entering Follower state 2015/08/21 19:52:22 [INFO] raft: Node at d2e32b9b-49e3-a067-f1d4-2c1c04014e5f [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at d2e32b9b-49e3-a067-f1d4-2c1c04014e5f [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at d2e32b9b-49e3-a067-f1d4-2c1c04014e5f [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 11579c9e-424d-ca96-4114-2097d7fd5a93 2015/08/21 19:52:23 [DEBUG] raft: Node d2e32b9b-49e3-a067-f1d4-2c1c04014e5f updated peer set (2): [d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [DEBUG] raft: Node 11579c9e-424d-ca96-4114-2097d7fd5a93 updated peer set (2): [d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [INFO] Applied log 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [INFO] raft: Node at 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:23 [INFO] raft: Node at 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:23 [DEBUG] raft: Node 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 updated peer set (2): [671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751] 2015/08/21 19:52:23 [DEBUG] raft: Node d2e32b9b-49e3-a067-f1d4-2c1c04014e5f updated peer set (2): [671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [INFO] raft: Added peer 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751, starting replication 2015/08/21 19:52:23 [WARN] raft: Failed to get previous log: 2 log not found (last: 1) 2015/08/21 19:52:23 [INFO] raft: Node at 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: AppendEntries to 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 rejected, sending older logs (next: 2) 2015/08/21 19:52:23 [DEBUG] raft: Node d2e32b9b-49e3-a067-f1d4-2c1c04014e5f updated peer set (2): [671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [DEBUG] raft: Node 11579c9e-424d-ca96-4114-2097d7fd5a93 updated peer set (2): [671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [DEBUG] raft: Node 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 updated peer set (2): [671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 d2e32b9b-49e3-a067-f1d4-2c1c04014e5f 11579c9e-424d-ca96-4114-2097d7fd5a93] 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 671c8c74-e21e-d5f0-7ba5-f5e2dd1a1751 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 11579c9e-424d-ca96-4114-2097d7fd5a93 2015/08/21 19:52:23 [ERR] raft: Failed to heartbeat to 11579c9e-424d-ca96-4114-2097d7fd5a93: command timed out --- PASS: TestRaft_JoinNode (0.23s) === RUN TestRaft_RemoveFollower 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [INFO] raft: Node at 5dd5702a-f826-3496-eb0c-353c1b648c07 [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at a258bb22-f1cb-6715-6aa6-dc601d4a298a [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 5dd5702a-f826-3496-eb0c-353c1b648c07 [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:23 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:23 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: Node at 5dd5702a-f826-3496-eb0c-353c1b648c07 [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 5dd5702a-f826-3496-eb0c-353c1b648c07 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer a258bb22-f1cb-6715-6aa6-dc601d4a298a 2015/08/21 19:52:23 [DEBUG] raft: Node 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab updated peer set (2): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab 5dd5702a-f826-3496-eb0c-353c1b648c07 a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [DEBUG] raft: Node 5dd5702a-f826-3496-eb0c-353c1b648c07 updated peer set (2): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab 5dd5702a-f826-3496-eb0c-353c1b648c07 a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [DEBUG] raft: Node a258bb22-f1cb-6715-6aa6-dc601d4a298a updated peer set (2): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab 5dd5702a-f826-3496-eb0c-353c1b648c07 a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [DEBUG] raft: Node 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab updated peer set (3): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [DEBUG] raft: Node 7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab updated peer set (3): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [INFO] raft: Removed peer 5dd5702a-f826-3496-eb0c-353c1b648c07, stopping replication (Index: 2) 2015/08/21 19:52:23 [DEBUG] raft: Node 5dd5702a-f826-3496-eb0c-353c1b648c07 updated peer set (3): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [INFO] raft: Removed ourself, shutting down 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 5dd5702a-f826-3496-eb0c-353c1b648c07 2015/08/21 19:52:23 [DEBUG] raft: Node a258bb22-f1cb-6715-6aa6-dc601d4a298a updated peer set (3): [7833b8cc-bdcf-8cb6-bdaa-8383d3a554ab a258bb22-f1cb-6715-6aa6-dc601d4a298a] 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer a258bb22-f1cb-6715-6aa6-dc601d4a298a 2015/08/21 19:52:23 [ERR] raft: Failed to heartbeat to a258bb22-f1cb-6715-6aa6-dc601d4a298a: command timed out --- PASS: TestRaft_RemoveFollower (0.18s) === RUN TestRaft_RemoveLeader 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [INFO] raft: Node at 9a108d82-183c-bb2d-bebe-423ea6b5d11c [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at 75d4b201-4391-b56e-83c2-e24dad8cd6f1 [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at 029d0198-bb8e-e182-88fc-4ca51c8d7b4a [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 75d4b201-4391-b56e-83c2-e24dad8cd6f1 [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at 75d4b201-4391-b56e-83c2-e24dad8cd6f1 [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 9a108d82-183c-bb2d-bebe-423ea6b5d11c 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 029d0198-bb8e-e182-88fc-4ca51c8d7b4a 2015/08/21 19:52:23 [DEBUG] raft: Node 75d4b201-4391-b56e-83c2-e24dad8cd6f1 updated peer set (2): [75d4b201-4391-b56e-83c2-e24dad8cd6f1 9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [DEBUG] raft: Node 9a108d82-183c-bb2d-bebe-423ea6b5d11c updated peer set (2): [75d4b201-4391-b56e-83c2-e24dad8cd6f1 9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [DEBUG] raft: Node 029d0198-bb8e-e182-88fc-4ca51c8d7b4a updated peer set (2): [75d4b201-4391-b56e-83c2-e24dad8cd6f1 9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [DEBUG] raft: Node 75d4b201-4391-b56e-83c2-e24dad8cd6f1 updated peer set (3): [9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [DEBUG] raft: Node 75d4b201-4391-b56e-83c2-e24dad8cd6f1 updated peer set (3): [9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [INFO] raft: Removed peer 9a108d82-183c-bb2d-bebe-423ea6b5d11c, stopping replication (Index: 2) 2015/08/21 19:52:23 [INFO] raft: Removed peer 029d0198-bb8e-e182-88fc-4ca51c8d7b4a, stopping replication (Index: 2) 2015/08/21 19:52:23 [INFO] raft: Removed ourself, shutting down 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 9a108d82-183c-bb2d-bebe-423ea6b5d11c 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 029d0198-bb8e-e182-88fc-4ca51c8d7b4a 2015/08/21 19:52:23 [DEBUG] raft: Node 9a108d82-183c-bb2d-bebe-423ea6b5d11c updated peer set (3): [9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [DEBUG] raft: Node 029d0198-bb8e-e182-88fc-4ca51c8d7b4a updated peer set (3): [9a108d82-183c-bb2d-bebe-423ea6b5d11c 029d0198-bb8e-e182-88fc-4ca51c8d7b4a] 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 029d0198-bb8e-e182-88fc-4ca51c8d7b4a [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [WARN] raft: Rejecting vote from 029d0198-bb8e-e182-88fc-4ca51c8d7b4a since we have a leader: 75d4b201-4391-b56e-83c2-e24dad8cd6f1 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 9a108d82-183c-bb2d-bebe-423ea6b5d11c [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [INFO] raft: Duplicate RequestVote for same term: 2 2015/08/21 19:52:23 [WARN] raft: Election timeout reached, restarting election 2015/08/21 19:52:23 [INFO] raft: Node at 029d0198-bb8e-e182-88fc-4ca51c8d7b4a [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [INFO] raft: Node at 9a108d82-183c-bb2d-bebe-423ea6b5d11c [Follower] entering Follower state 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at 029d0198-bb8e-e182-88fc-4ca51c8d7b4a [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer 9a108d82-183c-bb2d-bebe-423ea6b5d11c 2015/08/21 19:52:23 [DEBUG] raft: Node 029d0198-bb8e-e182-88fc-4ca51c8d7b4a updated peer set (2): [029d0198-bb8e-e182-88fc-4ca51c8d7b4a 9a108d82-183c-bb2d-bebe-423ea6b5d11c] 2015/08/21 19:52:23 [DEBUG] raft: Node 9a108d82-183c-bb2d-bebe-423ea6b5d11c updated peer set (2): [029d0198-bb8e-e182-88fc-4ca51c8d7b4a 9a108d82-183c-bb2d-bebe-423ea6b5d11c] 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer 9a108d82-183c-bb2d-bebe-423ea6b5d11c --- PASS: TestRaft_RemoveLeader (0.26s) === RUN TestRaft_RemoveLeader_NoShutdown 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [INFO] raft: Node at b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at a50421e0-7d94-d645-caad-306b6de9847a [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at 01505f74-0bd8-7e13-7178-85db26e51b99 [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at 01505f74-0bd8-7e13-7178-85db26e51b99 [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at 01505f74-0bd8-7e13-7178-85db26e51b99 [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer a50421e0-7d94-d645-caad-306b6de9847a 2015/08/21 19:52:23 [DEBUG] raft: Node 01505f74-0bd8-7e13-7178-85db26e51b99 updated peer set (2): [01505f74-0bd8-7e13-7178-85db26e51b99 b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [DEBUG] raft: Node b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 updated peer set (2): [01505f74-0bd8-7e13-7178-85db26e51b99 b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [DEBUG] raft: Node a50421e0-7d94-d645-caad-306b6de9847a updated peer set (2): [01505f74-0bd8-7e13-7178-85db26e51b99 b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [DEBUG] raft: Node 01505f74-0bd8-7e13-7178-85db26e51b99 updated peer set (3): [b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [DEBUG] raft: Node 01505f74-0bd8-7e13-7178-85db26e51b99 updated peer set (3): [b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [INFO] raft: Removed peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9, stopping replication (Index: 2) 2015/08/21 19:52:23 [INFO] raft: Removed peer a50421e0-7d94-d645-caad-306b6de9847a, stopping replication (Index: 2) 2015/08/21 19:52:23 [INFO] raft: Removed ourself, transitioning to follower 2015/08/21 19:52:23 [INFO] raft: Node at 01505f74-0bd8-7e13-7178-85db26e51b99 [Follower] entering Follower state 2015/08/21 19:52:23 [DEBUG] raft: Node b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 updated peer set (3): [b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [DEBUG] raft: Node a50421e0-7d94-d645-caad-306b6de9847a updated peer set (3): [b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 a50421e0-7d94-d645-caad-306b6de9847a] 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer a50421e0-7d94-d645-caad-306b6de9847a 2015/08/21 19:52:23 [WARN] raft: Clearing log suffix from 2 to 2 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at a50421e0-7d94-d645-caad-306b6de9847a [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [WARN] raft: Rejecting vote from a50421e0-7d94-d645-caad-306b6de9847a since we have a leader: 01505f74-0bd8-7e13-7178-85db26e51b99 2015/08/21 19:52:23 [WARN] raft: EnableSingleNode disabled, and no known peers. Aborting election. 2015/08/21 19:52:23 [WARN] raft: Election timeout reached, restarting election 2015/08/21 19:52:23 [INFO] raft: Node at a50421e0-7d94-d645-caad-306b6de9847a [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [WARN] raft: Rejecting vote from a50421e0-7d94-d645-caad-306b6de9847a since we have a leader: 01505f74-0bd8-7e13-7178-85db26e51b99 2015/08/21 19:52:23 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:23 [INFO] raft: Node at b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [DEBUG] raft: Newer term discovered, fallback to follower 2015/08/21 19:52:23 [INFO] raft: Node at b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 [Follower] entering Follower state 2015/08/21 19:52:23 [WARN] raft: Election timeout reached, restarting election 2015/08/21 19:52:23 [INFO] raft: Node at a50421e0-7d94-d645-caad-306b6de9847a [Candidate] entering Candidate state 2015/08/21 19:52:23 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:23 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:23 [INFO] raft: Node at a50421e0-7d94-d645-caad-306b6de9847a [Leader] entering Leader state 2015/08/21 19:52:23 [INFO] raft: pipelining replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 2015/08/21 19:52:23 [DEBUG] raft: Node a50421e0-7d94-d645-caad-306b6de9847a updated peer set (2): [a50421e0-7d94-d645-caad-306b6de9847a b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9] 2015/08/21 19:52:23 [DEBUG] raft: Node b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 updated peer set (2): [a50421e0-7d94-d645-caad-306b6de9847a b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9] 2015/08/21 19:52:23 [INFO] raft: aborting pipeline replication to peer b5e8a441-ecb8-03a4-e1f0-6e7f3dd531c9 --- PASS: TestRaft_RemoveLeader_NoShutdown (0.30s) === RUN TestRaft_RemoveLeader_SplitCluster 2015/08/21 19:52:23 [WARN] Fully Connecting 2015/08/21 19:52:23 [INFO] raft: Node at d43efac3-b6d7-8742-b9f4-40a93bd265fc [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at 3be4e8ee-1019-3b5a-5f2a-08019890d087 [Follower] entering Follower state 2015/08/21 19:52:23 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer d43efac3-b6d7-8742-b9f4-40a93bd265fc 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 3be4e8ee-1019-3b5a-5f2a-08019890d087 2015/08/21 19:52:24 [DEBUG] raft: Node a9b563d1-0db5-0e8f-45fa-aaebd329f9ea updated peer set (2): [a9b563d1-0db5-0e8f-45fa-aaebd329f9ea d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [DEBUG] raft: Node 3be4e8ee-1019-3b5a-5f2a-08019890d087 updated peer set (2): [a9b563d1-0db5-0e8f-45fa-aaebd329f9ea d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [DEBUG] raft: Node a9b563d1-0db5-0e8f-45fa-aaebd329f9ea updated peer set (3): [d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [DEBUG] raft: Node a9b563d1-0db5-0e8f-45fa-aaebd329f9ea updated peer set (3): [d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [INFO] raft: Removed peer d43efac3-b6d7-8742-b9f4-40a93bd265fc, stopping replication (Index: 2) 2015/08/21 19:52:24 [INFO] raft: Removed peer 3be4e8ee-1019-3b5a-5f2a-08019890d087, stopping replication (Index: 2) 2015/08/21 19:52:24 [INFO] raft: Removed ourself, transitioning to follower 2015/08/21 19:52:24 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Follower] entering Follower state 2015/08/21 19:52:24 [DEBUG] raft: Node d43efac3-b6d7-8742-b9f4-40a93bd265fc updated peer set (2): [a9b563d1-0db5-0e8f-45fa-aaebd329f9ea d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [DEBUG] raft: Node d43efac3-b6d7-8742-b9f4-40a93bd265fc updated peer set (3): [d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [DEBUG] raft: Node 3be4e8ee-1019-3b5a-5f2a-08019890d087 updated peer set (3): [d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 3be4e8ee-1019-3b5a-5f2a-08019890d087 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer d43efac3-b6d7-8742-b9f4-40a93bd265fc 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Node at a9b563d1-0db5-0e8f-45fa-aaebd329f9ea [Leader] entering Leader state 2015/08/21 19:52:24 [DEBUG] raft: Node a9b563d1-0db5-0e8f-45fa-aaebd329f9ea updated peer set (2): [a9b563d1-0db5-0e8f-45fa-aaebd329f9ea] 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at 3be4e8ee-1019-3b5a-5f2a-08019890d087 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [WARN] raft: Rejecting vote from 3be4e8ee-1019-3b5a-5f2a-08019890d087 since we have a leader: a9b563d1-0db5-0e8f-45fa-aaebd329f9ea 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at d43efac3-b6d7-8742-b9f4-40a93bd265fc [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Duplicate RequestVote for same term: 2 2015/08/21 19:52:24 [WARN] raft: Election timeout reached, restarting election 2015/08/21 19:52:24 [INFO] raft: Node at d43efac3-b6d7-8742-b9f4-40a93bd265fc [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Node at 3be4e8ee-1019-3b5a-5f2a-08019890d087 [Follower] entering Follower state 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at d43efac3-b6d7-8742-b9f4-40a93bd265fc [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 3be4e8ee-1019-3b5a-5f2a-08019890d087 2015/08/21 19:52:24 [DEBUG] raft: Node d43efac3-b6d7-8742-b9f4-40a93bd265fc updated peer set (2): [d43efac3-b6d7-8742-b9f4-40a93bd265fc 3be4e8ee-1019-3b5a-5f2a-08019890d087] 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 3be4e8ee-1019-3b5a-5f2a-08019890d087 --- PASS: TestRaft_RemoveLeader_SplitCluster (0.24s) === RUN TestRaft_AddKnownPeer 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at 3a6e4984-6381-6682-034b-cf402a9d0824 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at 7281afbf-f0ad-f6df-c742-548fcf7e8107 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at 7281afbf-f0ad-f6df-c742-548fcf7e8107 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at 7281afbf-f0ad-f6df-c742-548fcf7e8107 [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 3a6e4984-6381-6682-034b-cf402a9d0824 2015/08/21 19:52:24 [DEBUG] raft: Node 7281afbf-f0ad-f6df-c742-548fcf7e8107 updated peer set (2): [7281afbf-f0ad-f6df-c742-548fcf7e8107 bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 3a6e4984-6381-6682-034b-cf402a9d0824] 2015/08/21 19:52:24 [DEBUG] raft: Node bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 updated peer set (2): [7281afbf-f0ad-f6df-c742-548fcf7e8107 bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 3a6e4984-6381-6682-034b-cf402a9d0824] 2015/08/21 19:52:24 [DEBUG] raft: Node 3a6e4984-6381-6682-034b-cf402a9d0824 updated peer set (2): [7281afbf-f0ad-f6df-c742-548fcf7e8107 bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 3a6e4984-6381-6682-034b-cf402a9d0824] 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer bd464442-fb4f-1c71-7bb0-99fa4a10b1d1 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 3a6e4984-6381-6682-034b-cf402a9d0824 --- PASS: TestRaft_AddKnownPeer (0.08s) === RUN TestRaft_RemoveUnknownPeer 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at d3a566d7-a855-8783-4303-4fbbb83904e5 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at 087dd844-63c8-e0c6-33a6-6cab16061075 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at 087dd844-63c8-e0c6-33a6-6cab16061075 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at d3a566d7-a855-8783-4303-4fbbb83904e5 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:24 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:24 [INFO] raft: Duplicate RequestVote for same term: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at 087dd844-63c8-e0c6-33a6-6cab16061075 [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: Node at d3a566d7-a855-8783-4303-4fbbb83904e5 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer d3a566d7-a855-8783-4303-4fbbb83904e5 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4 2015/08/21 19:52:24 [DEBUG] raft: Node 087dd844-63c8-e0c6-33a6-6cab16061075 updated peer set (2): [087dd844-63c8-e0c6-33a6-6cab16061075 d3a566d7-a855-8783-4303-4fbbb83904e5 c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4] 2015/08/21 19:52:24 [DEBUG] raft: Node d3a566d7-a855-8783-4303-4fbbb83904e5 updated peer set (2): [087dd844-63c8-e0c6-33a6-6cab16061075 d3a566d7-a855-8783-4303-4fbbb83904e5 c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4] 2015/08/21 19:52:24 [DEBUG] raft: Node c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4 updated peer set (2): [087dd844-63c8-e0c6-33a6-6cab16061075 d3a566d7-a855-8783-4303-4fbbb83904e5 c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4] 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer d3a566d7-a855-8783-4303-4fbbb83904e5 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer c4ec1b3e-fb9d-17ae-ad1f-e675607d43b4 --- PASS: TestRaft_RemoveUnknownPeer (0.10s) === RUN TestRaft_SnapshotRestore 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at 6a1afbb9-ccff-d706-d914-b2f26e2db611 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at 6a1afbb9-ccff-d706-d914-b2f26e2db611 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Node at 6a1afbb9-ccff-d706-d914-b2f26e2db611 [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:24 [DEBUG] raft: Node 6a1afbb9-ccff-d706-d914-b2f26e2db611 updated peer set (2): [6a1afbb9-ccff-d706-d914-b2f26e2db611] 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 101 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft318051051/snapshots/1-101-1440186744446.tmp 2015/08/21 19:52:24 [INFO] raft: Compacting logs from 1 to 91 2015/08/21 19:52:24 [INFO] raft: Snapshot to 101 complete 2015/08/21 19:52:24 [INFO] raft: Restored from snapshot 1-101-1440186744446 --- PASS: TestRaft_SnapshotRestore (0.08s) === RUN TestRaft_SnapshotRestore_PeerChange 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at 11f66c24-1573-0ad7-091d-c0cf8c7f721d [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at fa69d426-d171-2d86-f775-a20dd1f248b6 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 11f66c24-1573-0ad7-091d-c0cf8c7f721d 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer fa69d426-d171-2d86-f775-a20dd1f248b6 2015/08/21 19:52:24 [DEBUG] raft: Node bc330b12-6921-55b8-cbd4-a344a3281463 updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 11f66c24-1573-0ad7-091d-c0cf8c7f721d fa69d426-d171-2d86-f775-a20dd1f248b6] 2015/08/21 19:52:24 [DEBUG] raft: Node fa69d426-d171-2d86-f775-a20dd1f248b6 updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 11f66c24-1573-0ad7-091d-c0cf8c7f721d fa69d426-d171-2d86-f775-a20dd1f248b6] 2015/08/21 19:52:24 [DEBUG] raft: Node 11f66c24-1573-0ad7-091d-c0cf8c7f721d updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 11f66c24-1573-0ad7-091d-c0cf8c7f721d fa69d426-d171-2d86-f775-a20dd1f248b6] 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 101 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft190157401/snapshots/1-101-1440186744553.tmp 2015/08/21 19:52:24 [INFO] raft: Compacting logs from 1 to 91 2015/08/21 19:52:24 [INFO] raft: Snapshot to 101 complete 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 11f66c24-1573-0ad7-091d-c0cf8c7f721d 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer fa69d426-d171-2d86-f775-a20dd1f248b6 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Restored from snapshot 1-101-1440186744553 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at 0cd395d8-d164-4d82-3e9a-99aaed5606ae [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: EnableSingleNode disabled, and no known peers. Aborting election. 2015/08/21 19:52:24 [WARN] raft: EnableSingleNode disabled, and no known peers. Aborting election. 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [WARN] raft: Remote peer e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 does not have local node bc330b12-6921-55b8-cbd4-a344a3281463 as a peer 2015/08/21 19:52:24 [WARN] raft: Remote peer 0cd395d8-d164-4d82-3e9a-99aaed5606ae does not have local node bc330b12-6921-55b8-cbd4-a344a3281463 as a peer 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at bc330b12-6921-55b8-cbd4-a344a3281463 [Leader] entering Leader state 2015/08/21 19:52:24 [WARN] raft: Failed to get previous log: 101 log not found (last: 0) 2015/08/21 19:52:24 [WARN] raft: Failed to get previous log: 101 log not found (last: 0) 2015/08/21 19:52:24 [WARN] raft: AppendEntries to e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 rejected, sending older logs (next: 1) 2015/08/21 19:52:24 [WARN] raft: AppendEntries to 0cd395d8-d164-4d82-3e9a-99aaed5606ae rejected, sending older logs (next: 1) 2015/08/21 19:52:24 [ERR] raft: Failed to get log at index 1: log not found 2015/08/21 19:52:24 [ERR] raft: Failed to get log at index 1: log not found 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft053727219/snapshots/1-101-1440186744679.tmp 2015/08/21 19:52:24 [INFO] raft: Copied 693 bytes to local snapshot 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft432863901/snapshots/1-101-1440186744680.tmp 2015/08/21 19:52:24 [INFO] raft: Copied 693 bytes to local snapshot 2015/08/21 19:52:24 [INFO] raft: Installed remote snapshot 2015/08/21 19:52:24 [INFO] raft: Installed remote snapshot 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 0cd395d8-d164-4d82-3e9a-99aaed5606ae 2015/08/21 19:52:24 [DEBUG] raft: Node bc330b12-6921-55b8-cbd4-a344a3281463 updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 0cd395d8-d164-4d82-3e9a-99aaed5606ae] 2015/08/21 19:52:24 [DEBUG] raft: Node 0cd395d8-d164-4d82-3e9a-99aaed5606ae updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 0cd395d8-d164-4d82-3e9a-99aaed5606ae] 2015/08/21 19:52:24 [DEBUG] raft: Node e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 updated peer set (2): [bc330b12-6921-55b8-cbd4-a344a3281463 e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 0cd395d8-d164-4d82-3e9a-99aaed5606ae] 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 0cd395d8-d164-4d82-3e9a-99aaed5606ae 2015/08/21 19:52:24 [ERR] raft: Failed to heartbeat to e50aa04e-b0e5-eb3d-e9f2-5e32802c0cf9: command timed out --- PASS: TestRaft_SnapshotRestore_PeerChange (0.30s) === RUN TestRaft_AutoSnapshot 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [INFO] raft: Node at f5321569-1a20-fd45-4802-c3ad1ff94bc2 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at f5321569-1a20-fd45-4802-c3ad1ff94bc2 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Node at f5321569-1a20-fd45-4802-c3ad1ff94bc2 [Leader] entering Leader state 2015/08/21 19:52:24 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:24 [DEBUG] raft: Node f5321569-1a20-fd45-4802-c3ad1ff94bc2 updated peer set (2): [f5321569-1a20-fd45-4802-c3ad1ff94bc2] 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 101 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft282157271/snapshots/1-101-1440186744828.tmp 2015/08/21 19:52:24 [INFO] raft: Compacting logs from 1 to 91 2015/08/21 19:52:24 [INFO] raft: Snapshot to 101 complete --- PASS: TestRaft_AutoSnapshot (0.12s) === RUN TestRaft_SendSnapshotFollower 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [WARN] Disconnecting 3a99aecc-0538-0d1d-fe05-122648a9bac3 2015/08/21 19:52:24 [INFO] raft: Node at 3a99aecc-0538-0d1d-fe05-122648a9bac3 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at f6cec05f-f102-9b5b-f775-1ecec06eb1a2 [Follower] entering Follower state 2015/08/21 19:52:24 [INFO] raft: Node at af6ed913-5925-4154-5298-187e17e0ed1c [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at f6cec05f-f102-9b5b-f775-1ecec06eb1a2 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [ERR] raft: Failed to make RequestVote RPC to 3a99aecc-0538-0d1d-fe05-122648a9bac3: failed to connect to peer: 3a99aecc-0538-0d1d-fe05-122648a9bac3 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:24 [INFO] raft: Node at f6cec05f-f102-9b5b-f775-1ecec06eb1a2 [Leader] entering Leader state 2015/08/21 19:52:24 [ERR] raft: Failed to AppendEntries to 3a99aecc-0538-0d1d-fe05-122648a9bac3: failed to connect to peer: 3a99aecc-0538-0d1d-fe05-122648a9bac3 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer af6ed913-5925-4154-5298-187e17e0ed1c 2015/08/21 19:52:24 [DEBUG] raft: Node f6cec05f-f102-9b5b-f775-1ecec06eb1a2 updated peer set (2): [f6cec05f-f102-9b5b-f775-1ecec06eb1a2 3a99aecc-0538-0d1d-fe05-122648a9bac3 af6ed913-5925-4154-5298-187e17e0ed1c] 2015/08/21 19:52:24 [DEBUG] raft: Node af6ed913-5925-4154-5298-187e17e0ed1c updated peer set (2): [f6cec05f-f102-9b5b-f775-1ecec06eb1a2 3a99aecc-0538-0d1d-fe05-122648a9bac3 af6ed913-5925-4154-5298-187e17e0ed1c] 2015/08/21 19:52:24 [INFO] Finished apply without behind follower 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 0 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft543054625/snapshots/0-0-1440186744942.tmp 2015/08/21 19:52:24 [INFO] raft: Snapshot to 0 complete 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 101 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft955737083/snapshots/1-101-1440186744943.tmp 2015/08/21 19:52:24 [INFO] raft: Compacting logs from 1 to 91 2015/08/21 19:52:24 [INFO] raft: Snapshot to 101 complete 2015/08/21 19:52:24 [INFO] raft: Starting snapshot up to 97 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft614723045/snapshots/1-97-1440186744946.tmp 2015/08/21 19:52:24 [INFO] raft: Compacting logs from 1 to 91 2015/08/21 19:52:24 [INFO] raft: Snapshot to 97 complete 2015/08/21 19:52:24 [WARN] Fully Connecting 2015/08/21 19:52:24 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:24 [INFO] raft: Node at 3a99aecc-0538-0d1d-fe05-122648a9bac3 [Candidate] entering Candidate state 2015/08/21 19:52:24 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:24 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:24 [INFO] raft: Node at 3a99aecc-0538-0d1d-fe05-122648a9bac3 [Follower] entering Follower state 2015/08/21 19:52:24 [WARN] raft: Rejecting vote from 3a99aecc-0538-0d1d-fe05-122648a9bac3 since we have a leader: f6cec05f-f102-9b5b-f775-1ecec06eb1a2 2015/08/21 19:52:24 [WARN] raft: Rejecting vote from 3a99aecc-0538-0d1d-fe05-122648a9bac3 since we have a leader: f6cec05f-f102-9b5b-f775-1ecec06eb1a2 2015/08/21 19:52:24 [ERR] raft: Failed to get log at index 1: log not found 2015/08/21 19:52:24 [INFO] snapshot: Creating new snapshot at /tmp/raft543054625/snapshots/1-101-1440186744948.tmp 2015/08/21 19:52:24 [INFO] raft: Copied 693 bytes to local snapshot 2015/08/21 19:52:24 [INFO] raft: Installed remote snapshot 2015/08/21 19:52:24 [WARN] raft: Skipping application of old log: 101 2015/08/21 19:52:24 [INFO] raft: pipelining replication to peer 3a99aecc-0538-0d1d-fe05-122648a9bac3 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer 3a99aecc-0538-0d1d-fe05-122648a9bac3 2015/08/21 19:52:24 [INFO] raft: aborting pipeline replication to peer af6ed913-5925-4154-5298-187e17e0ed1c 2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to 3a99aecc-0538-0d1d-fe05-122648a9bac3: command timed out 2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to af6ed913-5925-4154-5298-187e17e0ed1c: command timed out --- PASS: TestRaft_SendSnapshotFollower (0.15s) === RUN TestRaft_ReJoinFollower 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at 14ffb7d1-934e-1c54-963e-625fffcf5712 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 71336609-5fde-33cf-270f-0fa9e2b3f1bb [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 1f994bc3-bf1b-babf-6163-223be2af4701 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at 71336609-5fde-33cf-270f-0fa9e2b3f1bb [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at 71336609-5fde-33cf-270f-0fa9e2b3f1bb [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 14ffb7d1-934e-1c54-963e-625fffcf5712 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 1f994bc3-bf1b-babf-6163-223be2af4701 2015/08/21 19:52:25 [DEBUG] raft: Node 71336609-5fde-33cf-270f-0fa9e2b3f1bb updated peer set (2): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 14ffb7d1-934e-1c54-963e-625fffcf5712 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 1f994bc3-bf1b-babf-6163-223be2af4701 updated peer set (2): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 14ffb7d1-934e-1c54-963e-625fffcf5712 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 14ffb7d1-934e-1c54-963e-625fffcf5712 updated peer set (2): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 14ffb7d1-934e-1c54-963e-625fffcf5712 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 71336609-5fde-33cf-270f-0fa9e2b3f1bb updated peer set (3): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 71336609-5fde-33cf-270f-0fa9e2b3f1bb updated peer set (3): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [INFO] raft: Removed peer 14ffb7d1-934e-1c54-963e-625fffcf5712, stopping replication (Index: 2) 2015/08/21 19:52:25 [DEBUG] raft: Node 14ffb7d1-934e-1c54-963e-625fffcf5712 updated peer set (3): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [INFO] raft: Removed ourself, transitioning to follower 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 14ffb7d1-934e-1c54-963e-625fffcf5712 2015/08/21 19:52:25 [DEBUG] raft: Node 1f994bc3-bf1b-babf-6163-223be2af4701 updated peer set (3): [71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 71336609-5fde-33cf-270f-0fa9e2b3f1bb updated peer set (2): [14ffb7d1-934e-1c54-963e-625fffcf5712 71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [INFO] raft: Added peer 14ffb7d1-934e-1c54-963e-625fffcf5712, starting replication 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 14ffb7d1-934e-1c54-963e-625fffcf5712 2015/08/21 19:52:25 [DEBUG] raft: Node 71336609-5fde-33cf-270f-0fa9e2b3f1bb updated peer set (2): [14ffb7d1-934e-1c54-963e-625fffcf5712 71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 1f994bc3-bf1b-babf-6163-223be2af4701 updated peer set (2): [14ffb7d1-934e-1c54-963e-625fffcf5712 71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [DEBUG] raft: Node 14ffb7d1-934e-1c54-963e-625fffcf5712 updated peer set (2): [14ffb7d1-934e-1c54-963e-625fffcf5712 71336609-5fde-33cf-270f-0fa9e2b3f1bb 1f994bc3-bf1b-babf-6163-223be2af4701] 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 14ffb7d1-934e-1c54-963e-625fffcf5712 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 1f994bc3-bf1b-babf-6163-223be2af4701 2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to 1f994bc3-bf1b-babf-6163-223be2af4701: command timed out --- PASS: TestRaft_ReJoinFollower (0.26s) === RUN TestRaft_Barrier 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at 790a9412-886b-6160-5527-bdbde4d6be70 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 790a9412-886b-6160-5527-bdbde4d6be70 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 2015/08/21 19:52:25 [DEBUG] raft: Node dd8a41b6-ddc6-3ac7-95f3-af641b85b839 updated peer set (2): [dd8a41b6-ddc6-3ac7-95f3-af641b85b839 790a9412-886b-6160-5527-bdbde4d6be70 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768] 2015/08/21 19:52:25 [DEBUG] raft: Node 790a9412-886b-6160-5527-bdbde4d6be70 updated peer set (2): [dd8a41b6-ddc6-3ac7-95f3-af641b85b839 790a9412-886b-6160-5527-bdbde4d6be70 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768] 2015/08/21 19:52:25 [DEBUG] raft: Node 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 updated peer set (2): [dd8a41b6-ddc6-3ac7-95f3-af641b85b839 790a9412-886b-6160-5527-bdbde4d6be70 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768] 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 790a9412-886b-6160-5527-bdbde4d6be70 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 --- PASS: TestRaft_Barrier (0.09s) === RUN TestRaft_VerifyLeader 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at b1cd7c66-a0bf-222d-a086-2c0198eb5f79 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 70450dd8-4161-87e8-77af-b53d5583ff67 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at 70450dd8-4161-87e8-77af-b53d5583ff67 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at 70450dd8-4161-87e8-77af-b53d5583ff67 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer b1cd7c66-a0bf-222d-a086-2c0198eb5f79 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e 2015/08/21 19:52:25 [DEBUG] raft: Node 70450dd8-4161-87e8-77af-b53d5583ff67 updated peer set (2): [70450dd8-4161-87e8-77af-b53d5583ff67 b1cd7c66-a0bf-222d-a086-2c0198eb5f79 54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e] 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer b1cd7c66-a0bf-222d-a086-2c0198eb5f79 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e --- PASS: TestRaft_VerifyLeader (0.06s) === RUN TestRaft_VerifyLeader_Single 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 1 2015/08/21 19:52:25 [INFO] raft: Node at a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: Disabling EnableSingleNode (bootstrap) 2015/08/21 19:52:25 [DEBUG] raft: Node a96449c4-9bfc-ca7f-acb6-582bc0fcd678 updated peer set (2): [a96449c4-9bfc-ca7f-acb6-582bc0fcd678] --- PASS: TestRaft_VerifyLeader_Single (0.10s) === RUN TestRaft_VerifyLeader_Fail 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at 53128c5a-caf4-67c1-bb47-18d2588ca006 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 1a8f0d37-4926-047d-5a1e-173da9fcb448 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at 1a8f0d37-4926-047d-5a1e-173da9fcb448 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at 1a8f0d37-4926-047d-5a1e-173da9fcb448 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 53128c5a-caf4-67c1-bb47-18d2588ca006 2015/08/21 19:52:25 [DEBUG] raft: Node 1a8f0d37-4926-047d-5a1e-173da9fcb448 updated peer set (2): [1a8f0d37-4926-047d-5a1e-173da9fcb448 53128c5a-caf4-67c1-bb47-18d2588ca006] 2015/08/21 19:52:25 [DEBUG] raft: Node 53128c5a-caf4-67c1-bb47-18d2588ca006 updated peer set (2): [1a8f0d37-4926-047d-5a1e-173da9fcb448 53128c5a-caf4-67c1-bb47-18d2588ca006] 2015/08/21 19:52:25 [WARN] raft: New leader elected, stepping down 2015/08/21 19:52:25 [INFO] raft: Node at 1a8f0d37-4926-047d-5a1e-173da9fcb448 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 53128c5a-caf4-67c1-bb47-18d2588ca006 --- PASS: TestRaft_VerifyLeader_Fail (0.08s) === RUN TestRaft_VerifyLeader_ParitalConnect 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at 04e0b808-56df-9a2e-d6fe-b8952958f641 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 02f25c2e-afac-6a0c-da05-d2de959e8f4c [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 975c21e9-a021-6d28-b10b-5340f1fb8b36 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at 04e0b808-56df-9a2e-d6fe-b8952958f641 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at 04e0b808-56df-9a2e-d6fe-b8952958f641 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 02f25c2e-afac-6a0c-da05-d2de959e8f4c 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 975c21e9-a021-6d28-b10b-5340f1fb8b36 2015/08/21 19:52:25 [DEBUG] raft: Node 04e0b808-56df-9a2e-d6fe-b8952958f641 updated peer set (2): [04e0b808-56df-9a2e-d6fe-b8952958f641 02f25c2e-afac-6a0c-da05-d2de959e8f4c 975c21e9-a021-6d28-b10b-5340f1fb8b36] 2015/08/21 19:52:25 [DEBUG] raft: Node 975c21e9-a021-6d28-b10b-5340f1fb8b36 updated peer set (2): [04e0b808-56df-9a2e-d6fe-b8952958f641 02f25c2e-afac-6a0c-da05-d2de959e8f4c 975c21e9-a021-6d28-b10b-5340f1fb8b36] 2015/08/21 19:52:25 [DEBUG] raft: Node 02f25c2e-afac-6a0c-da05-d2de959e8f4c updated peer set (2): [04e0b808-56df-9a2e-d6fe-b8952958f641 02f25c2e-afac-6a0c-da05-d2de959e8f4c 975c21e9-a021-6d28-b10b-5340f1fb8b36] 2015/08/21 19:52:25 [INFO] Disconnecting Node at 02f25c2e-afac-6a0c-da05-d2de959e8f4c [Follower] 2015/08/21 19:52:25 [WARN] Disconnecting 02f25c2e-afac-6a0c-da05-d2de959e8f4c 2015/08/21 19:52:25 [ERR] raft: Failed to pipeline AppendEntries to 02f25c2e-afac-6a0c-da05-d2de959e8f4c: append pipeline closed 2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to 02f25c2e-afac-6a0c-da05-d2de959e8f4c: failed to connect to peer: 02f25c2e-afac-6a0c-da05-d2de959e8f4c 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 02f25c2e-afac-6a0c-da05-d2de959e8f4c 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 975c21e9-a021-6d28-b10b-5340f1fb8b36 --- PASS: TestRaft_VerifyLeader_ParitalConnect (0.09s) === RUN TestRaft_SettingPeers 2015/08/21 19:52:25 [WARN] Fully Connecting 2015/08/21 19:52:25 [INFO] raft: Node at d2ca38f8-08b9-98e8-ce62-1bb0a38dd449 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 536ef089-76e5-bea4-a180-0cbaacb89595 [Follower] entering Follower state 2015/08/21 19:52:25 [INFO] raft: Node at 757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Follower] entering Follower state 2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting election 2015/08/21 19:52:25 [INFO] raft: Node at 757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Candidate] entering Candidate state 2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1 2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2 2015/08/21 19:52:25 [INFO] raft: Node at 757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Leader] entering Leader state 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer d2ca38f8-08b9-98e8-ce62-1bb0a38dd449 2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer 536ef089-76e5-bea4-a180-0cbaacb89595 2015/08/21 19:52:25 [DEBUG] raft: Node 757afb5c-ca3a-c7f9-c9af-617ce13ca082 updated peer set (2): [757afb5c-ca3a-c7f9-c9af-617ce13ca082 d2ca38f8-08b9-98e8-ce62-1bb0a38dd449 536ef089-76e5-bea4-a180-0cbaacb89595] 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer d2ca38f8-08b9-98e8-ce62-1bb0a38dd449 2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer 536ef089-76e5-bea4-a180-0cbaacb89595 --- PASS: TestRaft_SettingPeers (0.08s) === RUN TestTCPTransport_BadAddr --- PASS: TestTCPTransport_BadAddr (0.00s) === RUN TestTCPTransport_WithAdvertise --- PASS: TestTCPTransport_WithAdvertise (0.00s) === RUN TestRandomTimeout --- PASS: TestRandomTimeout (0.00s) === RUN TestNewSeed --- PASS: TestNewSeed (0.00s) === RUN TestRandomTimeout_NoTime --- PASS: TestRandomTimeout_NoTime (0.00s) === RUN TestMin --- PASS: TestMin (0.00s) === RUN TestMax --- PASS: TestMax (0.00s) === RUN TestGenerateUUID --- PASS: TestGenerateUUID (0.03s) === RUN TestAsyncNotify --- PASS: TestAsyncNotify (0.00s) === RUN TestExcludePeer --- PASS: TestExcludePeer (0.00s) === RUN TestPeerContained --- PASS: TestPeerContained (0.00s) === RUN TestAddUniquePeer --- PASS: TestAddUniquePeer (0.00s) === RUN TestEncodeDecodePeers --- PASS: TestEncodeDecodePeers (0.00s) === RUN TestBackoff --- PASS: TestBackoff (0.00s) FAIL FAIL github.com/hashicorp/raft 3.596s ? github.com/hashicorp/raft/bench [no test files] dh_auto_test: go test -v github.com/hashicorp/raft github.com/hashicorp/raft/bench returned exit code 1 debian/rules:9: recipe for target 'build' failed make: *** [build] Error 1 dpkg-buildpackage: error: debian/rules build gave error exit status 2 E: Failed autobuilding of package W: no hooks of type C found -- ignoring I: unmounting /sys filesystem I: unmounting /dev/shm filesystem I: unmounting dev/pts filesystem I: unmounting run/shm filesystem I: unmounting proc filesystem I: cleaning the build env I: removing directory /var/cache/pbuilder/build//52620 and its subdirectories