Diff of the two buildlogs: -- --- b1/build.log 2022-08-24 23:33:12.411311992 +0000 +++ b2/build.log 2022-08-24 23:40:41.938440707 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed Aug 24 11:21:53 -12 2022 -I: pbuilder-time-stamp: 1661383313 +I: Current time: Wed Sep 27 19:56:16 +14 2023 +I: pbuilder-time-stamp: 1695794176 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/buster-reproducible-base.tgz] I: copying local configuration @@ -17,7 +17,7 @@ I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' gpgv: keyblock resource '/root/.gnupg/trustedkeys.kbx': General error -gpgv: Signature made Mon Jul 9 00:57:43 2018 -12 +gpgv: Signature made Tue Jul 10 02:57:43 2018 +14 gpgv: using RSA key B4A0AAFCA86C86055D067614AB53E503586877A6 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./python-dogpile.cache_0.6.2-6.dsc @@ -30,136 +30,171 @@ dpkg-source: info: applying py37-compat.patch I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2638997/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/D01_modify_environment starting +debug: Running on ionos5-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +Removing 'diversion of /bin/sh to /bin/sh.distrib by dash' +Adding 'diversion of /bin/sh to /bin/sh.distrib by bash' +Removing 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by dash' +Adding 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by bash' +lrwxrwxrwx 1 root root 4 Sep 27 19:56 /bin/sh -> bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=15' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="0" [2]="3" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.0.3(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=16' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='a1654ca90b0e4bbc866aa57d04836ca2' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='2638997' - PS1='# ' - PS2='> ' + INVOCATION_ID=43ffb059206d4e0288c1f573d626484e + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=1551875 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.duJXNx7PqA/pbuilderrc_zpgE --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/buster-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.duJXNx7PqA/b1 --logfile b1/build.log python-dogpile.cache_0.6.2-6.dsc' - SUDO_GID='110' - SUDO_UID='105' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.duJXNx7PqA/pbuilderrc_mYoi --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/buster-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.duJXNx7PqA/b2 --logfile b2/build.log python-dogpile.cache_0.6.2-6.dsc' + SUDO_GID=110 + SUDO_UID=105 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://85.184.249.68:3128 I: uname -a - Linux ionos1-amd64 5.10.0-17-amd64 #1 SMP Debian 5.10.136-1 (2022-08-13) x86_64 GNU/Linux + Linux i-capture-the-hostname 5.18.0-0.deb11.3-amd64 #1 SMP PREEMPT_DYNAMIC Debian 5.18.14-1~bpo11+1 (2022-07-28) x86_64 GNU/Linux I: ls -l /bin total 5116 - -rwxr-xr-x 1 root root 1168776 Apr 17 2019 bash - -rwxr-xr-x 3 root root 38984 Jul 10 2019 bunzip2 - -rwxr-xr-x 3 root root 38984 Jul 10 2019 bzcat - lrwxrwxrwx 1 root root 6 Jul 10 2019 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2227 Jul 10 2019 bzdiff - lrwxrwxrwx 1 root root 6 Jul 10 2019 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4877 Jun 24 2019 bzexe - lrwxrwxrwx 1 root root 6 Jul 10 2019 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3641 Jul 10 2019 bzgrep - -rwxr-xr-x 3 root root 38984 Jul 10 2019 bzip2 - -rwxr-xr-x 1 root root 14328 Jul 10 2019 bzip2recover - lrwxrwxrwx 1 root root 6 Jul 10 2019 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Jul 10 2019 bzmore - -rwxr-xr-x 1 root root 43744 Feb 28 2019 cat - -rwxr-xr-x 1 root root 64320 Feb 28 2019 chgrp - -rwxr-xr-x 1 root root 64288 Feb 28 2019 chmod - -rwxr-xr-x 1 root root 72512 Feb 28 2019 chown - -rwxr-xr-x 1 root root 146880 Feb 28 2019 cp - -rwxr-xr-x 1 root root 121464 Jan 17 2019 dash - -rwxr-xr-x 1 root root 109408 Feb 28 2019 date - -rwxr-xr-x 1 root root 76712 Feb 28 2019 dd - -rwxr-xr-x 1 root root 93744 Feb 28 2019 df - -rwxr-xr-x 1 root root 138856 Feb 28 2019 dir - -rwxr-xr-x 1 root root 84288 Jan 9 2019 dmesg - lrwxrwxrwx 1 root root 8 Sep 26 2018 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Sep 26 2018 domainname -> hostname - -rwxr-xr-x 1 root root 39520 Feb 28 2019 echo - -rwxr-xr-x 1 root root 28 Jan 7 2019 egrep - -rwxr-xr-x 1 root root 35424 Feb 28 2019 false - -rwxr-xr-x 1 root root 28 Jan 7 2019 fgrep - -rwxr-xr-x 1 root root 68880 Jan 9 2019 findmnt - -rwsr-xr-x 1 root root 34896 Apr 22 2020 fusermount - -rwxr-xr-x 1 root root 198976 Jan 7 2019 grep - -rwxr-xr-x 2 root root 2345 Jan 5 2019 gunzip - -rwxr-xr-x 1 root root 6375 Jan 5 2019 gzexe - -rwxr-xr-x 1 root root 98048 Jan 5 2019 gzip - -rwxr-xr-x 1 root root 26696 Sep 26 2018 hostname - -rwxr-xr-x 1 root root 68552 Feb 28 2019 ln - -rwxr-xr-x 1 root root 56760 Jul 26 2018 login - -rwxr-xr-x 1 root root 138856 Feb 28 2019 ls - -rwxr-xr-x 1 root root 108624 Jan 9 2019 lsblk - -rwxr-xr-x 1 root root 89088 Feb 28 2019 mkdir - -rwxr-xr-x 1 root root 68544 Feb 28 2019 mknod - -rwxr-xr-x 1 root root 43808 Feb 28 2019 mktemp - -rwxr-xr-x 1 root root 43008 Jan 9 2019 more - -rwsr-xr-x 1 root root 51280 Jan 9 2019 mount - -rwxr-xr-x 1 root root 14408 Jan 9 2019 mountpoint - -rwxr-xr-x 1 root root 138728 Feb 28 2019 mv - lrwxrwxrwx 1 root root 8 Sep 26 2018 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Feb 14 2019 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 39616 Feb 28 2019 pwd - lrwxrwxrwx 1 root root 4 Apr 17 2019 rbash -> bash - -rwxr-xr-x 1 root root 47776 Feb 28 2019 readlink - -rwxr-xr-x 1 root root 68416 Feb 28 2019 rm - -rwxr-xr-x 1 root root 47776 Feb 28 2019 rmdir - -rwxr-xr-x 1 root root 23312 Jan 21 2019 run-parts - -rwxr-xr-x 1 root root 122224 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Aug 6 20:25 sh -> dash - -rwxr-xr-x 1 root root 39552 Feb 28 2019 sleep - -rwxr-xr-x 1 root root 80672 Feb 28 2019 stty - -rwsr-xr-x 1 root root 63568 Jan 9 2019 su - -rwxr-xr-x 1 root root 35488 Feb 28 2019 sync - -rwxr-xr-x 1 root root 445560 Apr 23 2019 tar - -rwxr-xr-x 1 root root 14440 Jan 21 2019 tempfile - -rwxr-xr-x 1 root root 97152 Feb 28 2019 touch - -rwxr-xr-x 1 root root 35424 Feb 28 2019 true - -rwxr-xr-x 1 root root 14328 Apr 22 2020 ulockmgr_server - -rwsr-xr-x 1 root root 34888 Jan 9 2019 umount - -rwxr-xr-x 1 root root 39584 Feb 28 2019 uname - -rwxr-xr-x 2 root root 2345 Jan 5 2019 uncompress - -rwxr-xr-x 1 root root 138856 Feb 28 2019 vdir - -rwxr-xr-x 1 root root 34896 Jan 9 2019 wdctl - -rwxr-xr-x 1 root root 946 Jan 21 2019 which - lrwxrwxrwx 1 root root 8 Sep 26 2018 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1983 Jan 5 2019 zcat - -rwxr-xr-x 1 root root 1677 Jan 5 2019 zcmp - -rwxr-xr-x 1 root root 5879 Jan 5 2019 zdiff - -rwxr-xr-x 1 root root 29 Jan 5 2019 zegrep - -rwxr-xr-x 1 root root 29 Jan 5 2019 zfgrep - -rwxr-xr-x 1 root root 2080 Jan 5 2019 zforce - -rwxr-xr-x 1 root root 7584 Jan 5 2019 zgrep - -rwxr-xr-x 1 root root 2205 Jan 5 2019 zless - -rwxr-xr-x 1 root root 1841 Jan 5 2019 zmore - -rwxr-xr-x 1 root root 4552 Jan 5 2019 znew -I: user script /srv/workspace/pbuilder/2638997/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1168776 Apr 18 2019 bash + -rwxr-xr-x 3 root root 38984 Jul 11 2019 bunzip2 + -rwxr-xr-x 3 root root 38984 Jul 11 2019 bzcat + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2227 Jul 11 2019 bzdiff + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Jun 25 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3641 Jul 11 2019 bzgrep + -rwxr-xr-x 3 root root 38984 Jul 11 2019 bzip2 + -rwxr-xr-x 1 root root 14328 Jul 11 2019 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 11 2019 bzmore + -rwxr-xr-x 1 root root 43744 Mar 1 2019 cat + -rwxr-xr-x 1 root root 64320 Mar 1 2019 chgrp + -rwxr-xr-x 1 root root 64288 Mar 1 2019 chmod + -rwxr-xr-x 1 root root 72512 Mar 1 2019 chown + -rwxr-xr-x 1 root root 146880 Mar 1 2019 cp + -rwxr-xr-x 1 root root 121464 Jan 18 2019 dash + -rwxr-xr-x 1 root root 109408 Mar 1 2019 date + -rwxr-xr-x 1 root root 76712 Mar 1 2019 dd + -rwxr-xr-x 1 root root 93744 Mar 1 2019 df + -rwxr-xr-x 1 root root 138856 Mar 1 2019 dir + -rwxr-xr-x 1 root root 84288 Jan 10 2019 dmesg + lrwxrwxrwx 1 root root 8 Sep 27 2018 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Sep 27 2018 domainname -> hostname + -rwxr-xr-x 1 root root 39520 Mar 1 2019 echo + -rwxr-xr-x 1 root root 28 Jan 8 2019 egrep + -rwxr-xr-x 1 root root 35424 Mar 1 2019 false + -rwxr-xr-x 1 root root 28 Jan 8 2019 fgrep + -rwxr-xr-x 1 root root 68880 Jan 10 2019 findmnt + -rwsr-xr-x 1 root root 34896 Apr 23 2020 fusermount + -rwxr-xr-x 1 root root 198976 Jan 8 2019 grep + -rwxr-xr-x 2 root root 2345 Jan 6 2019 gunzip + -rwxr-xr-x 1 root root 6375 Jan 6 2019 gzexe + -rwxr-xr-x 1 root root 98048 Jan 6 2019 gzip + -rwxr-xr-x 1 root root 26696 Sep 27 2018 hostname + -rwxr-xr-x 1 root root 68552 Mar 1 2019 ln + -rwxr-xr-x 1 root root 56760 Jul 27 2018 login + -rwxr-xr-x 1 root root 138856 Mar 1 2019 ls + -rwxr-xr-x 1 root root 108624 Jan 10 2019 lsblk + -rwxr-xr-x 1 root root 89088 Mar 1 2019 mkdir + -rwxr-xr-x 1 root root 68544 Mar 1 2019 mknod + -rwxr-xr-x 1 root root 43808 Mar 1 2019 mktemp + -rwxr-xr-x 1 root root 43008 Jan 10 2019 more + -rwsr-xr-x 1 root root 51280 Jan 10 2019 mount + -rwxr-xr-x 1 root root 14408 Jan 10 2019 mountpoint + -rwxr-xr-x 1 root root 138728 Mar 1 2019 mv + lrwxrwxrwx 1 root root 8 Sep 27 2018 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Feb 15 2019 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 39616 Mar 1 2019 pwd + lrwxrwxrwx 1 root root 4 Apr 18 2019 rbash -> bash + -rwxr-xr-x 1 root root 47776 Mar 1 2019 readlink + -rwxr-xr-x 1 root root 68416 Mar 1 2019 rm + -rwxr-xr-x 1 root root 47776 Mar 1 2019 rmdir + -rwxr-xr-x 1 root root 23312 Jan 22 2019 run-parts + -rwxr-xr-x 1 root root 122224 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Sep 27 19:56 sh -> bash + lrwxrwxrwx 1 root root 4 Sep 10 04:47 sh.distrib -> dash + -rwxr-xr-x 1 root root 39552 Mar 1 2019 sleep + -rwxr-xr-x 1 root root 80672 Mar 1 2019 stty + -rwsr-xr-x 1 root root 63568 Jan 10 2019 su + -rwxr-xr-x 1 root root 35488 Mar 1 2019 sync + -rwxr-xr-x 1 root root 445560 Apr 24 2019 tar + -rwxr-xr-x 1 root root 14440 Jan 22 2019 tempfile + -rwxr-xr-x 1 root root 97152 Mar 1 2019 touch + -rwxr-xr-x 1 root root 35424 Mar 1 2019 true + -rwxr-xr-x 1 root root 14328 Apr 23 2020 ulockmgr_server + -rwsr-xr-x 1 root root 34888 Jan 10 2019 umount + -rwxr-xr-x 1 root root 39584 Mar 1 2019 uname + -rwxr-xr-x 2 root root 2345 Jan 6 2019 uncompress + -rwxr-xr-x 1 root root 138856 Mar 1 2019 vdir + -rwxr-xr-x 1 root root 34896 Jan 10 2019 wdctl + -rwxr-xr-x 1 root root 946 Jan 22 2019 which + lrwxrwxrwx 1 root root 8 Sep 27 2018 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1983 Jan 6 2019 zcat + -rwxr-xr-x 1 root root 1677 Jan 6 2019 zcmp + -rwxr-xr-x 1 root root 5879 Jan 6 2019 zdiff + -rwxr-xr-x 1 root root 29 Jan 6 2019 zegrep + -rwxr-xr-x 1 root root 29 Jan 6 2019 zfgrep + -rwxr-xr-x 1 root root 2080 Jan 6 2019 zforce + -rwxr-xr-x 1 root root 7584 Jan 6 2019 zgrep + -rwxr-xr-x 1 root root 2205 Jan 6 2019 zless + -rwxr-xr-x 1 root root 1841 Jan 6 2019 zmore + -rwxr-xr-x 1 root root 4552 Jan 6 2019 znew +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -354,7 +389,7 @@ Get: 123 http://deb.debian.org/debian buster/main amd64 python3-pluggy all 0.8.0-1 [20.6 kB] Get: 124 http://deb.debian.org/debian buster/main amd64 python3-py all 1.7.0-2 [86.6 kB] Get: 125 http://deb.debian.org/debian buster/main amd64 python3-pytest all 3.10.1-2 [243 kB] -Fetched 41.7 MB in 2s (17.7 MB/s) +Fetched 41.7 MB in 2s (21.5 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libbsd0: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 ... 19195 files and directories currently installed.) @@ -910,7 +945,8 @@ fakeroot is already the newest version (1.23-1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/python-dogpile.cache-0.6.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../python-dogpile.cache_0.6.2-6_source.changes +hostname: Name or service not known +I: Running cd /build/python-dogpile.cache-0.6.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../python-dogpile.cache_0.6.2-6_source.changes dpkg-buildpackage: info: source package python-dogpile.cache dpkg-buildpackage: info: source version 0.6.2-6 dpkg-buildpackage: info: source distribution unstable @@ -1003,7 +1039,7 @@ collecting ... collected 496 items tests/test_backgrounding.py::TestAsyncRunner::test_async_release PASSED [ 0%] -tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_long_create PASSED [ 0%] +tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_long_create FAILED [ 0%] tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_registry_safe_cache_quick PASSED [ 0%] tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_registry_unsafe_cache PASSED [ 0%] tests/test_lock.py::ConcurrencyTest::test_quick PASSED [ 1%] @@ -1499,1077 +1535,1071 @@ tests/cache/plugins/test_mako_cache.py::TestMakoPlugin::test_timeout PASSED [ 99%] tests/util/test_nameregistry.py::NameRegistryTest::test_name_registry PASSED [100%] +=================================== FAILURES =================================== +___________ ConcurrencyTest.test_get_value_plus_created_long_create ____________ +Traceback (most recent call last): + File "/build/python-dogpile.cache-0.6.2/tests/test_lock.py", line 42, in test_get_value_plus_created_long_create + 10, 2, 2.5, 50, .05, .1, + File "/build/python-dogpile.cache-0.6.2/tests/test_lock.py", line 261, in _test_multi + assert actual_run_time <= expected_run_time +AssertionError: assert 17.88935685157776 <= 15.675000000000002 +----------------------------- Captured stderr call ----------------------------- +2023-09-27 19:59:53,883 DEBUG [tests.test_lock] [139783598315264] creating resource, will take 2.500000 sec +2023-09-27 19:59:56,395 DEBUG [tests.test_lock] [139783598315264] finished creating resource +2023-09-27 19:59:56,395 DEBUG [tests.test_lock] [139783598315264] Time since create 0.0004 max stale time 5.115, total waited 1 +2023-09-27 19:59:56,399 DEBUG [tests.test_lock] [139783589922560] Time since create 0.0041 max stale time 5.115, total waited 2 +2023-09-27 19:59:56,399 DEBUG [tests.test_lock] [139783581529856] Time since create 0.0045 max stale time 5.115, total waited 3 +2023-09-27 19:59:56,400 DEBUG [tests.test_lock] [139783573137152] Time since create 0.0046 max stale time 5.115, total waited 4 +2023-09-27 19:59:56,400 DEBUG [tests.test_lock] [139783564744448] Time since create 0.0048 max stale time 5.115, total waited 5 +2023-09-27 19:59:56,403 DEBUG [tests.test_lock] [139783556351744] Time since create 0.0081 max stale time 5.115, total waited 6 +2023-09-27 19:59:56,407 DEBUG [tests.test_lock] [139783547959040] Time since create 0.0121 max stale time 5.115, total waited 7 +2023-09-27 19:59:56,411 DEBUG [tests.test_lock] [139783066089216] Time since create 0.0161 max stale time 5.115, total waited 8 +2023-09-27 19:59:56,415 DEBUG [tests.test_lock] [139783057696512] Time since create 0.0201 max stale time 5.115, total waited 9 +2023-09-27 19:59:56,420 DEBUG [tests.test_lock] [139783049303808] Time since create 0.0246 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,551 DEBUG [tests.test_lock] [139783598315264] Time since create 0.1561 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,555 DEBUG [tests.test_lock] [139783573137152] Time since create 0.1600 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,555 DEBUG [tests.test_lock] [139783564744448] Time since create 0.1604 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,556 DEBUG [tests.test_lock] [139783581529856] Time since create 0.1605 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,556 DEBUG [tests.test_lock] [139783589922560] Time since create 0.1607 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,559 DEBUG [tests.test_lock] [139783556351744] Time since create 0.1640 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,563 DEBUG [tests.test_lock] [139783547959040] Time since create 0.1680 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,567 DEBUG [tests.test_lock] [139783066089216] Time since create 0.1720 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,571 DEBUG [tests.test_lock] [139783049303808] Time since create 0.1760 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,575 DEBUG [tests.test_lock] [139783057696512] Time since create 0.1800 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,707 DEBUG [tests.test_lock] [139783573137152] Time since create 0.3121 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,707 DEBUG [tests.test_lock] [139783564744448] Time since create 0.3125 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,711 DEBUG [tests.test_lock] [139783598315264] Time since create 0.3160 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,715 DEBUG [tests.test_lock] [139783581529856] Time since create 0.3200 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,715 DEBUG [tests.test_lock] [139783589922560] Time since create 0.3204 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,716 DEBUG [tests.test_lock] [139783556351744] Time since create 0.3212 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,723 DEBUG [tests.test_lock] [139783066089216] Time since create 0.3280 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,727 DEBUG [tests.test_lock] [139783547959040] Time since create 0.3320 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,731 DEBUG [tests.test_lock] [139783057696512] Time since create 0.3361 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,732 DEBUG [tests.test_lock] [139783049303808] Time since create 0.3370 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,863 DEBUG [tests.test_lock] [139783573137152] Time since create 0.4677 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,863 DEBUG [tests.test_lock] [139783564744448] Time since create 0.4681 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,867 DEBUG [tests.test_lock] [139783598315264] Time since create 0.4720 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,871 DEBUG [tests.test_lock] [139783556351744] Time since create 0.4760 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,875 DEBUG [tests.test_lock] [139783581529856] Time since create 0.4801 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,875 DEBUG [tests.test_lock] [139783589922560] Time since create 0.4804 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,879 DEBUG [tests.test_lock] [139783066089216] Time since create 0.4836 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,883 DEBUG [tests.test_lock] [139783547959040] Time since create 0.4880 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,887 DEBUG [tests.test_lock] [139783057696512] Time since create 0.4921 max stale time 5.115, total waited 10 +2023-09-27 19:59:56,891 DEBUG [tests.test_lock] [139783049303808] Time since create 0.4960 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,019 DEBUG [tests.test_lock] [139783573137152] Time since create 0.6240 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,019 DEBUG [tests.test_lock] [139783564744448] Time since create 0.6244 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,023 DEBUG [tests.test_lock] [139783598315264] Time since create 0.6280 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,027 DEBUG [tests.test_lock] [139783556351744] Time since create 0.6320 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,031 DEBUG [tests.test_lock] [139783581529856] Time since create 0.6360 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,031 DEBUG [tests.test_lock] [139783589922560] Time since create 0.6364 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,034 DEBUG [tests.test_lock] [139783066089216] Time since create 0.6385 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,039 DEBUG [tests.test_lock] [139783547959040] Time since create 0.6440 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,043 DEBUG [tests.test_lock] [139783057696512] Time since create 0.6481 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,047 DEBUG [tests.test_lock] [139783049303808] Time since create 0.6520 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,175 DEBUG [tests.test_lock] [139783573137152] Time since create 0.7801 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,175 DEBUG [tests.test_lock] [139783564744448] Time since create 0.7804 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,179 DEBUG [tests.test_lock] [139783598315264] Time since create 0.7840 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,183 DEBUG [tests.test_lock] [139783556351744] Time since create 0.7880 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,187 DEBUG [tests.test_lock] [139783581529856] Time since create 0.7920 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,187 DEBUG [tests.test_lock] [139783589922560] Time since create 0.7924 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,188 DEBUG [tests.test_lock] [139783066089216] Time since create 0.7933 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,195 DEBUG [tests.test_lock] [139783547959040] Time since create 0.8000 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,198 DEBUG [tests.test_lock] [139783057696512] Time since create 0.8029 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,203 DEBUG [tests.test_lock] [139783049303808] Time since create 0.8080 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,331 DEBUG [tests.test_lock] [139783573137152] Time since create 0.9361 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,331 DEBUG [tests.test_lock] [139783564744448] Time since create 0.9364 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,335 DEBUG [tests.test_lock] [139783598315264] Time since create 0.9401 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,337 DEBUG [tests.test_lock] [139783556351744] Time since create 0.9425 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,341 DEBUG [tests.test_lock] [139783581529856] Time since create 0.9463 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,342 DEBUG [tests.test_lock] [139783589922560] Time since create 0.9467 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,343 DEBUG [tests.test_lock] [139783066089216] Time since create 0.9480 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,351 DEBUG [tests.test_lock] [139783547959040] Time since create 0.9560 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,355 DEBUG [tests.test_lock] [139783057696512] Time since create 0.9600 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,355 DEBUG [tests.test_lock] [139783049303808] Time since create 0.9605 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,491 DEBUG [tests.test_lock] [139783598315264] Time since create 1.0961 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,495 DEBUG [tests.test_lock] [139783573137152] Time since create 1.1000 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,495 DEBUG [tests.test_lock] [139783564744448] Time since create 1.1004 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,496 DEBUG [tests.test_lock] [139783581529856] Time since create 1.1006 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,496 DEBUG [tests.test_lock] [139783589922560] Time since create 1.1008 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,499 DEBUG [tests.test_lock] [139783066089216] Time since create 1.1041 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,503 DEBUG [tests.test_lock] [139783556351744] Time since create 1.1080 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,507 DEBUG [tests.test_lock] [139783547959040] Time since create 1.1120 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,511 DEBUG [tests.test_lock] [139783057696512] Time since create 1.1161 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,515 DEBUG [tests.test_lock] [139783049303808] Time since create 1.1200 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,647 DEBUG [tests.test_lock] [139783598315264] Time since create 1.2521 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,655 DEBUG [tests.test_lock] [139783573137152] Time since create 1.2601 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,655 DEBUG [tests.test_lock] [139783564744448] Time since create 1.2604 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,656 DEBUG [tests.test_lock] [139783581529856] Time since create 1.2606 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,656 DEBUG [tests.test_lock] [139783589922560] Time since create 1.2607 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,659 DEBUG [tests.test_lock] [139783066089216] Time since create 1.2641 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,663 DEBUG [tests.test_lock] [139783547959040] Time since create 1.2680 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,667 DEBUG [tests.test_lock] [139783556351744] Time since create 1.2721 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,669 DEBUG [tests.test_lock] [139783057696512] Time since create 1.2741 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,671 DEBUG [tests.test_lock] [139783049303808] Time since create 1.2760 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,803 DEBUG [tests.test_lock] [139783598315264] Time since create 1.4080 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,811 DEBUG [tests.test_lock] [139783573137152] Time since create 1.4160 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,811 DEBUG [tests.test_lock] [139783564744448] Time since create 1.4164 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,812 DEBUG [tests.test_lock] [139783581529856] Time since create 1.4165 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,812 DEBUG [tests.test_lock] [139783589922560] Time since create 1.4167 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,818 DEBUG [tests.test_lock] [139783066089216] Time since create 1.4229 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,819 DEBUG [tests.test_lock] [139783547959040] Time since create 1.4236 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,823 DEBUG [tests.test_lock] [139783556351744] Time since create 1.4281 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,827 DEBUG [tests.test_lock] [139783057696512] Time since create 1.4320 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,831 DEBUG [tests.test_lock] [139783049303808] Time since create 1.4360 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,959 DEBUG [tests.test_lock] [139783598315264] Time since create 1.5640 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,966 DEBUG [tests.test_lock] [139783573137152] Time since create 1.5714 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,967 DEBUG [tests.test_lock] [139783564744448] Time since create 1.5718 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,967 DEBUG [tests.test_lock] [139783581529856] Time since create 1.5720 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,967 DEBUG [tests.test_lock] [139783589922560] Time since create 1.5722 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,975 DEBUG [tests.test_lock] [139783547959040] Time since create 1.5800 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,979 DEBUG [tests.test_lock] [139783066089216] Time since create 1.5840 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,983 DEBUG [tests.test_lock] [139783556351744] Time since create 1.5880 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,986 DEBUG [tests.test_lock] [139783057696512] Time since create 1.5908 max stale time 5.115, total waited 10 +2023-09-27 19:59:57,991 DEBUG [tests.test_lock] [139783049303808] Time since create 1.5960 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,114 DEBUG [tests.test_lock] [139783598315264] Time since create 1.7185 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,123 DEBUG [tests.test_lock] [139783573137152] Time since create 1.7280 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,123 DEBUG [tests.test_lock] [139783564744448] Time since create 1.7284 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,124 DEBUG [tests.test_lock] [139783581529856] Time since create 1.7286 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,124 DEBUG [tests.test_lock] [139783589922560] Time since create 1.7287 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,131 DEBUG [tests.test_lock] [139783547959040] Time since create 1.7360 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,135 DEBUG [tests.test_lock] [139783066089216] Time since create 1.7404 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,139 DEBUG [tests.test_lock] [139783556351744] Time since create 1.7440 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,143 DEBUG [tests.test_lock] [139783057696512] Time since create 1.7480 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,147 DEBUG [tests.test_lock] [139783049303808] Time since create 1.7520 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,270 DEBUG [tests.test_lock] [139783598315264] Time since create 1.8749 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,287 DEBUG [tests.test_lock] [139783547959040] Time since create 1.8920 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,288 DEBUG [tests.test_lock] [139783573137152] Time since create 1.8929 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,288 DEBUG [tests.test_lock] [139783564744448] Time since create 1.8933 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,288 DEBUG [tests.test_lock] [139783581529856] Time since create 1.8934 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,289 DEBUG [tests.test_lock] [139783589922560] Time since create 1.8936 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,289 DEBUG [tests.test_lock] [139783066089216] Time since create 1.8943 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,295 DEBUG [tests.test_lock] [139783556351744] Time since create 1.9000 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,299 DEBUG [tests.test_lock] [139783057696512] Time since create 1.9038 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,300 DEBUG [tests.test_lock] [139783049303808] Time since create 1.9048 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,427 DEBUG [tests.test_lock] [139783598315264] creating resource, will take 2.500000 sec +2023-09-27 19:59:58,443 DEBUG [tests.test_lock] [139783547959040] Time since create 2.0481 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,443 DEBUG [tests.test_lock] [139783581529856] Time since create 2.0484 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,455 DEBUG [tests.test_lock] [139783057696512] Time since create 2.0604 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,444 DEBUG [tests.test_lock] [139783589922560] Time since create 2.0485 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,444 DEBUG [tests.test_lock] [139783564744448] Time since create 2.0486 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,444 DEBUG [tests.test_lock] [139783573137152] Time since create 2.0487 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,451 DEBUG [tests.test_lock] [139783556351744] Time since create 2.0561 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,463 DEBUG [tests.test_lock] [139783066089216] Time since create 2.0681 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,467 DEBUG [tests.test_lock] [139783049303808] Time since create 2.0721 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,603 DEBUG [tests.test_lock] [139783547959040] Time since create 2.2081 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,615 DEBUG [tests.test_lock] [139783556351744] Time since create 2.2201 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,619 DEBUG [tests.test_lock] [139783066089216] Time since create 2.2240 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,620 DEBUG [tests.test_lock] [139783057696512] Time since create 2.2251 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,620 DEBUG [tests.test_lock] [139783589922560] Time since create 2.2254 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,621 DEBUG [tests.test_lock] [139783564744448] Time since create 2.2256 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,621 DEBUG [tests.test_lock] [139783573137152] Time since create 2.2257 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,621 DEBUG [tests.test_lock] [139783581529856] Time since create 2.2258 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,623 DEBUG [tests.test_lock] [139783049303808] Time since create 2.2280 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,763 DEBUG [tests.test_lock] [139783547959040] Time since create 2.3681 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,770 DEBUG [tests.test_lock] [139783556351744] Time since create 2.3751 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,775 DEBUG [tests.test_lock] [139783581529856] Time since create 2.3802 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,776 DEBUG [tests.test_lock] [139783573137152] Time since create 2.3807 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,776 DEBUG [tests.test_lock] [139783564744448] Time since create 2.3809 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,776 DEBUG [tests.test_lock] [139783589922560] Time since create 2.3811 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,776 DEBUG [tests.test_lock] [139783057696512] Time since create 2.3812 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,779 DEBUG [tests.test_lock] [139783066089216] Time since create 2.3801 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,783 DEBUG [tests.test_lock] [139783049303808] Time since create 2.3880 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,919 DEBUG [tests.test_lock] [139783547959040] Time since create 2.5241 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,927 DEBUG [tests.test_lock] [139783556351744] Time since create 2.5320 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,931 DEBUG [tests.test_lock] [139783581529856] Time since create 2.5361 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,931 DEBUG [tests.test_lock] [139783573137152] Time since create 2.5365 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,932 DEBUG [tests.test_lock] [139783564744448] Time since create 2.5367 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,932 DEBUG [tests.test_lock] [139783589922560] Time since create 2.5368 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,932 DEBUG [tests.test_lock] [139783057696512] Time since create 2.5370 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,935 DEBUG [tests.test_lock] [139783066089216] Time since create 2.5401 max stale time 5.115, total waited 10 +2023-09-27 19:59:58,939 DEBUG [tests.test_lock] [139783049303808] Time since create 2.5441 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,072 DEBUG [tests.test_lock] [139783547959040] Time since create 2.6770 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,083 DEBUG [tests.test_lock] [139783556351744] Time since create 2.6881 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,084 DEBUG [tests.test_lock] [139783581529856] Time since create 2.6891 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,084 DEBUG [tests.test_lock] [139783573137152] Time since create 2.6894 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,085 DEBUG [tests.test_lock] [139783564744448] Time since create 2.6896 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,085 DEBUG [tests.test_lock] [139783589922560] Time since create 2.6897 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,085 DEBUG [tests.test_lock] [139783057696512] Time since create 2.6898 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,091 DEBUG [tests.test_lock] [139783066089216] Time since create 2.6960 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,094 DEBUG [tests.test_lock] [139783049303808] Time since create 2.6985 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,223 DEBUG [tests.test_lock] [139783547959040] Time since create 2.8281 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,239 DEBUG [tests.test_lock] [139783556351744] Time since create 2.8441 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,240 DEBUG [tests.test_lock] [139783564744448] Time since create 2.8447 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,240 DEBUG [tests.test_lock] [139783589922560] Time since create 2.8451 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,240 DEBUG [tests.test_lock] [139783573137152] Time since create 2.8452 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,240 DEBUG [tests.test_lock] [139783581529856] Time since create 2.8454 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,241 DEBUG [tests.test_lock] [139783057696512] Time since create 2.8458 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,247 DEBUG [tests.test_lock] [139783066089216] Time since create 2.8521 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,251 DEBUG [tests.test_lock] [139783049303808] Time since create 2.8561 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,379 DEBUG [tests.test_lock] [139783547959040] Time since create 2.9841 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,391 DEBUG [tests.test_lock] [139783556351744] Time since create 2.9961 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,395 DEBUG [tests.test_lock] [139783564744448] Time since create 3.0001 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,395 DEBUG [tests.test_lock] [139783589922560] Time since create 3.0004 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,396 DEBUG [tests.test_lock] [139783573137152] Time since create 3.0006 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,396 DEBUG [tests.test_lock] [139783581529856] Time since create 3.0008 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,396 DEBUG [tests.test_lock] [139783057696512] Time since create 3.0014 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,403 DEBUG [tests.test_lock] [139783066089216] Time since create 3.0081 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,411 DEBUG [tests.test_lock] [139783049303808] Time since create 3.0160 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,531 DEBUG [tests.test_lock] [139783547959040] Time since create 3.1361 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,546 DEBUG [tests.test_lock] [139783556351744] Time since create 3.1510 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,555 DEBUG [tests.test_lock] [139783564744448] Time since create 3.1601 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,555 DEBUG [tests.test_lock] [139783589922560] Time since create 3.1604 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,556 DEBUG [tests.test_lock] [139783573137152] Time since create 3.1605 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,556 DEBUG [tests.test_lock] [139783581529856] Time since create 3.1607 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,556 DEBUG [tests.test_lock] [139783057696512] Time since create 3.1608 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,559 DEBUG [tests.test_lock] [139783066089216] Time since create 3.1641 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,563 DEBUG [tests.test_lock] [139783049303808] Time since create 3.1681 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,683 DEBUG [tests.test_lock] [139783547959040] Time since create 3.2881 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,700 DEBUG [tests.test_lock] [139783556351744] Time since create 3.3048 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,711 DEBUG [tests.test_lock] [139783564744448] Time since create 3.3161 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,711 DEBUG [tests.test_lock] [139783589922560] Time since create 3.3165 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,712 DEBUG [tests.test_lock] [139783573137152] Time since create 3.3166 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,712 DEBUG [tests.test_lock] [139783581529856] Time since create 3.3168 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,712 DEBUG [tests.test_lock] [139783057696512] Time since create 3.3170 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,715 DEBUG [tests.test_lock] [139783066089216] Time since create 3.3201 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,716 DEBUG [tests.test_lock] [139783049303808] Time since create 3.3208 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,838 DEBUG [tests.test_lock] [139783547959040] Time since create 3.4425 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,859 DEBUG [tests.test_lock] [139783556351744] Time since create 3.4641 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,867 DEBUG [tests.test_lock] [139783564744448] Time since create 3.4721 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,867 DEBUG [tests.test_lock] [139783589922560] Time since create 3.4724 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,868 DEBUG [tests.test_lock] [139783573137152] Time since create 3.4727 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,868 DEBUG [tests.test_lock] [139783581529856] Time since create 3.4728 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,868 DEBUG [tests.test_lock] [139783057696512] Time since create 3.4730 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,871 DEBUG [tests.test_lock] [139783049303808] Time since create 3.4761 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,875 DEBUG [tests.test_lock] [139783066089216] Time since create 3.4801 max stale time 5.115, total waited 10 +2023-09-27 19:59:59,995 DEBUG [tests.test_lock] [139783547959040] Time since create 3.6001 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,015 DEBUG [tests.test_lock] [139783556351744] Time since create 3.6200 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,023 DEBUG [tests.test_lock] [139783564744448] Time since create 3.6281 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,023 DEBUG [tests.test_lock] [139783589922560] Time since create 3.6284 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,024 DEBUG [tests.test_lock] [139783573137152] Time since create 3.6286 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,024 DEBUG [tests.test_lock] [139783581529856] Time since create 3.6287 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,024 DEBUG [tests.test_lock] [139783057696512] Time since create 3.6289 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,027 DEBUG [tests.test_lock] [139783049303808] Time since create 3.6323 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,031 DEBUG [tests.test_lock] [139783066089216] Time since create 3.6361 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,155 DEBUG [tests.test_lock] [139783547959040] Time since create 3.7601 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,171 DEBUG [tests.test_lock] [139783556351744] Time since create 3.7761 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,179 DEBUG [tests.test_lock] [139783564744448] Time since create 3.7841 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,179 DEBUG [tests.test_lock] [139783589922560] Time since create 3.7844 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,180 DEBUG [tests.test_lock] [139783573137152] Time since create 3.7845 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,180 DEBUG [tests.test_lock] [139783581529856] Time since create 3.7846 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,180 DEBUG [tests.test_lock] [139783057696512] Time since create 3.7848 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,184 DEBUG [tests.test_lock] [139783049303808] Time since create 3.7890 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,187 DEBUG [tests.test_lock] [139783066089216] Time since create 3.7921 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,315 DEBUG [tests.test_lock] [139783547959040] Time since create 3.9200 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,327 DEBUG [tests.test_lock] [139783556351744] Time since create 3.9321 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,335 DEBUG [tests.test_lock] [139783564744448] Time since create 3.9401 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,335 DEBUG [tests.test_lock] [139783589922560] Time since create 3.9405 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,336 DEBUG [tests.test_lock] [139783573137152] Time since create 3.9407 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,336 DEBUG [tests.test_lock] [139783581529856] Time since create 3.9408 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,336 DEBUG [tests.test_lock] [139783057696512] Time since create 3.9410 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,339 DEBUG [tests.test_lock] [139783049303808] Time since create 3.9441 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,343 DEBUG [tests.test_lock] [139783066089216] Time since create 3.9480 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,471 DEBUG [tests.test_lock] [139783547959040] Time since create 4.0761 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,487 DEBUG [tests.test_lock] [139783556351744] Time since create 4.0921 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,491 DEBUG [tests.test_lock] [139783564744448] Time since create 4.0961 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,491 DEBUG [tests.test_lock] [139783589922560] Time since create 4.0965 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,492 DEBUG [tests.test_lock] [139783573137152] Time since create 4.0967 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,492 DEBUG [tests.test_lock] [139783581529856] Time since create 4.0969 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,492 DEBUG [tests.test_lock] [139783057696512] Time since create 4.0971 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,495 DEBUG [tests.test_lock] [139783049303808] Time since create 4.1001 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,499 DEBUG [tests.test_lock] [139783066089216] Time since create 4.1041 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,627 DEBUG [tests.test_lock] [139783547959040] Time since create 4.2321 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,643 DEBUG [tests.test_lock] [139783556351744] Time since create 4.2481 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,643 DEBUG [tests.test_lock] [139783564744448] Time since create 4.2484 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,651 DEBUG [tests.test_lock] [139783573137152] Time since create 4.2563 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,652 DEBUG [tests.test_lock] [139783581529856] Time since create 4.2565 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,652 DEBUG [tests.test_lock] [139783057696512] Time since create 4.2567 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,644 DEBUG [tests.test_lock] [139783589922560] Time since create 4.2485 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,655 DEBUG [tests.test_lock] [139783066089216] Time since create 4.2601 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,655 DEBUG [tests.test_lock] [139783049303808] Time since create 4.2605 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,783 DEBUG [tests.test_lock] [139783547959040] Time since create 4.3881 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,803 DEBUG [tests.test_lock] [139783556351744] Time since create 4.4081 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,807 DEBUG [tests.test_lock] [139783564744448] Time since create 4.4123 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,808 DEBUG [tests.test_lock] [139783573137152] Time since create 4.4127 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,808 DEBUG [tests.test_lock] [139783581529856] Time since create 4.4128 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,808 DEBUG [tests.test_lock] [139783057696512] Time since create 4.4130 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,808 DEBUG [tests.test_lock] [139783589922560] Time since create 4.4132 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,811 DEBUG [tests.test_lock] [139783066089216] Time since create 4.4161 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,815 DEBUG [tests.test_lock] [139783049303808] Time since create 4.4200 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,939 DEBUG [tests.test_lock] [139783547959040] Time since create 4.5441 max stale time 5.115, total waited 10 +2023-09-27 20:00:00,943 DEBUG [tests.test_lock] [139783598315264] finished creating resource +2023-09-27 20:00:00,943 DEBUG [tests.test_lock] [139783598315264] Time since create 0.0003 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,959 DEBUG [tests.test_lock] [139783556351744] Time since create 0.0161 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,962 DEBUG [tests.test_lock] [139783564744448] Time since create 0.0187 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,962 DEBUG [tests.test_lock] [139783573137152] Time since create 0.0191 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,962 DEBUG [tests.test_lock] [139783581529856] Time since create 0.0192 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,963 DEBUG [tests.test_lock] [139783057696512] Time since create 0.0196 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,963 DEBUG [tests.test_lock] [139783589922560] Time since create 0.0198 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,965 DEBUG [tests.test_lock] [139783066089216] Time since create 0.0217 max stale time 5.115, total waited 11 +2023-09-27 20:00:00,975 DEBUG [tests.test_lock] [139783049303808] Time since create 0.0323 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,095 DEBUG [tests.test_lock] [139783547959040] Time since create 0.1520 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,099 DEBUG [tests.test_lock] [139783598315264] Time since create 0.1560 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,115 DEBUG [tests.test_lock] [139783556351744] Time since create 0.1721 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,119 DEBUG [tests.test_lock] [139783564744448] Time since create 0.1760 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,119 DEBUG [tests.test_lock] [139783573137152] Time since create 0.1764 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,120 DEBUG [tests.test_lock] [139783581529856] Time since create 0.1765 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,120 DEBUG [tests.test_lock] [139783057696512] Time since create 0.1767 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,120 DEBUG [tests.test_lock] [139783589922560] Time since create 0.1769 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,123 DEBUG [tests.test_lock] [139783066089216] Time since create 0.1801 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,127 DEBUG [tests.test_lock] [139783049303808] Time since create 0.1841 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,248 DEBUG [tests.test_lock] [139783547959040] Time since create 0.3046 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,255 DEBUG [tests.test_lock] [139783598315264] Time since create 0.3121 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,271 DEBUG [tests.test_lock] [139783556351744] Time since create 0.3281 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,275 DEBUG [tests.test_lock] [139783564744448] Time since create 0.3321 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,275 DEBUG [tests.test_lock] [139783573137152] Time since create 0.3324 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,276 DEBUG [tests.test_lock] [139783581529856] Time since create 0.3326 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,276 DEBUG [tests.test_lock] [139783057696512] Time since create 0.3327 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,276 DEBUG [tests.test_lock] [139783589922560] Time since create 0.3328 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,279 DEBUG [tests.test_lock] [139783066089216] Time since create 0.3360 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,280 DEBUG [tests.test_lock] [139783049303808] Time since create 0.3367 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,407 DEBUG [tests.test_lock] [139783547959040] Time since create 0.4641 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,411 DEBUG [tests.test_lock] [139783598315264] Time since create 0.4681 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,427 DEBUG [tests.test_lock] [139783556351744] Time since create 0.4841 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,435 DEBUG [tests.test_lock] [139783049303808] Time since create 0.4921 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,435 DEBUG [tests.test_lock] [139783066089216] Time since create 0.4925 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,447 DEBUG [tests.test_lock] [139783564744448] Time since create 0.5040 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,447 DEBUG [tests.test_lock] [139783573137152] Time since create 0.5044 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,448 DEBUG [tests.test_lock] [139783581529856] Time since create 0.5045 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,448 DEBUG [tests.test_lock] [139783057696512] Time since create 0.5047 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,448 DEBUG [tests.test_lock] [139783589922560] Time since create 0.5048 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,563 DEBUG [tests.test_lock] [139783547959040] Time since create 0.6201 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,571 DEBUG [tests.test_lock] [139783598315264] Time since create 0.6279 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,583 DEBUG [tests.test_lock] [139783556351744] Time since create 0.6401 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,588 DEBUG [tests.test_lock] [139783049303808] Time since create 0.6450 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,588 DEBUG [tests.test_lock] [139783066089216] Time since create 0.6454 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,603 DEBUG [tests.test_lock] [139783564744448] Time since create 0.6600 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,603 DEBUG [tests.test_lock] [139783573137152] Time since create 0.6605 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,604 DEBUG [tests.test_lock] [139783581529856] Time since create 0.6607 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,604 DEBUG [tests.test_lock] [139783057696512] Time since create 0.6608 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,604 DEBUG [tests.test_lock] [139783589922560] Time since create 0.6610 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,719 DEBUG [tests.test_lock] [139783547959040] Time since create 0.7760 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,727 DEBUG [tests.test_lock] [139783598315264] Time since create 0.7841 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,743 DEBUG [tests.test_lock] [139783049303808] Time since create 0.8001 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,744 DEBUG [tests.test_lock] [139783066089216] Time since create 0.8005 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,747 DEBUG [tests.test_lock] [139783556351744] Time since create 0.8041 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,759 DEBUG [tests.test_lock] [139783564744448] Time since create 0.8160 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,759 DEBUG [tests.test_lock] [139783573137152] Time since create 0.8164 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,760 DEBUG [tests.test_lock] [139783581529856] Time since create 0.8166 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,760 DEBUG [tests.test_lock] [139783057696512] Time since create 0.8167 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,760 DEBUG [tests.test_lock] [139783589922560] Time since create 0.8170 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,875 DEBUG [tests.test_lock] [139783547959040] Time since create 0.9321 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,883 DEBUG [tests.test_lock] [139783598315264] Time since create 0.9400 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,899 DEBUG [tests.test_lock] [139783049303808] Time since create 0.9560 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,899 DEBUG [tests.test_lock] [139783066089216] Time since create 0.9564 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,903 DEBUG [tests.test_lock] [139783556351744] Time since create 0.9601 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,915 DEBUG [tests.test_lock] [139783564744448] Time since create 0.9721 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,915 DEBUG [tests.test_lock] [139783573137152] Time since create 0.9725 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,916 DEBUG [tests.test_lock] [139783581529856] Time since create 0.9726 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,916 DEBUG [tests.test_lock] [139783057696512] Time since create 0.9728 max stale time 5.115, total waited 11 +2023-09-27 20:00:01,916 DEBUG [tests.test_lock] [139783589922560] Time since create 0.9729 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,031 DEBUG [tests.test_lock] [139783547959040] Time since create 1.0880 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,039 DEBUG [tests.test_lock] [139783598315264] Time since create 1.0961 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,059 DEBUG [tests.test_lock] [139783556351744] Time since create 1.1160 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,063 DEBUG [tests.test_lock] [139783066089216] Time since create 1.1200 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,063 DEBUG [tests.test_lock] [139783049303808] Time since create 1.1204 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,071 DEBUG [tests.test_lock] [139783564744448] Time since create 1.1276 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,071 DEBUG [tests.test_lock] [139783573137152] Time since create 1.1280 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,071 DEBUG [tests.test_lock] [139783581529856] Time since create 1.1282 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,071 DEBUG [tests.test_lock] [139783057696512] Time since create 1.1284 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,071 DEBUG [tests.test_lock] [139783589922560] Time since create 1.1285 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,187 DEBUG [tests.test_lock] [139783547959040] Time since create 1.2440 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,191 DEBUG [tests.test_lock] [139783598315264] Time since create 1.2481 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,214 DEBUG [tests.test_lock] [139783556351744] Time since create 1.2706 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,216 DEBUG [tests.test_lock] [139783066089216] Time since create 1.2727 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,216 DEBUG [tests.test_lock] [139783049303808] Time since create 1.2732 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,227 DEBUG [tests.test_lock] [139783564744448] Time since create 1.2840 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,227 DEBUG [tests.test_lock] [139783573137152] Time since create 1.2844 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,228 DEBUG [tests.test_lock] [139783581529856] Time since create 1.2845 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,228 DEBUG [tests.test_lock] [139783057696512] Time since create 1.2847 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,228 DEBUG [tests.test_lock] [139783589922560] Time since create 1.2849 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,343 DEBUG [tests.test_lock] [139783547959040] Time since create 1.4001 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,347 DEBUG [tests.test_lock] [139783598315264] Time since create 1.4041 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,371 DEBUG [tests.test_lock] [139783556351744] Time since create 1.4280 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,375 DEBUG [tests.test_lock] [139783066089216] Time since create 1.4321 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,375 DEBUG [tests.test_lock] [139783049303808] Time since create 1.4324 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,383 DEBUG [tests.test_lock] [139783564744448] Time since create 1.4400 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,383 DEBUG [tests.test_lock] [139783573137152] Time since create 1.4404 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,384 DEBUG [tests.test_lock] [139783581529856] Time since create 1.4405 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,384 DEBUG [tests.test_lock] [139783057696512] Time since create 1.4407 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,384 DEBUG [tests.test_lock] [139783589922560] Time since create 1.4408 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,499 DEBUG [tests.test_lock] [139783547959040] Time since create 1.5560 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,503 DEBUG [tests.test_lock] [139783598315264] Time since create 1.5601 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,527 DEBUG [tests.test_lock] [139783556351744] Time since create 1.5840 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,531 DEBUG [tests.test_lock] [139783066089216] Time since create 1.5881 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,531 DEBUG [tests.test_lock] [139783049303808] Time since create 1.5885 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,535 DEBUG [tests.test_lock] [139783564744448] Time since create 1.5920 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,543 DEBUG [tests.test_lock] [139783573137152] Time since create 1.6000 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,543 DEBUG [tests.test_lock] [139783581529856] Time since create 1.6004 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,544 DEBUG [tests.test_lock] [139783057696512] Time since create 1.6005 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,544 DEBUG [tests.test_lock] [139783589922560] Time since create 1.6007 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,655 DEBUG [tests.test_lock] [139783547959040] Time since create 1.7121 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,659 DEBUG [tests.test_lock] [139783598315264] Time since create 1.7160 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,679 DEBUG [tests.test_lock] [139783556351744] Time since create 1.7357 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,685 DEBUG [tests.test_lock] [139783066089216] Time since create 1.7421 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,685 DEBUG [tests.test_lock] [139783049303808] Time since create 1.7425 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,691 DEBUG [tests.test_lock] [139783564744448] Time since create 1.7480 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,699 DEBUG [tests.test_lock] [139783573137152] Time since create 1.7561 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,700 DEBUG [tests.test_lock] [139783581529856] Time since create 1.7565 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,700 DEBUG [tests.test_lock] [139783057696512] Time since create 1.7567 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,700 DEBUG [tests.test_lock] [139783589922560] Time since create 1.7568 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,815 DEBUG [tests.test_lock] [139783598315264] Time since create 1.8720 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,819 DEBUG [tests.test_lock] [139783547959040] Time since create 1.8760 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,835 DEBUG [tests.test_lock] [139783556351744] Time since create 1.8920 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,841 DEBUG [tests.test_lock] [139783066089216] Time since create 1.8984 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,842 DEBUG [tests.test_lock] [139783049303808] Time since create 1.8988 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,847 DEBUG [tests.test_lock] [139783564744448] Time since create 1.9041 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,858 DEBUG [tests.test_lock] [139783573137152] Time since create 1.9150 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,858 DEBUG [tests.test_lock] [139783581529856] Time since create 1.9153 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,858 DEBUG [tests.test_lock] [139783057696512] Time since create 1.9155 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,859 DEBUG [tests.test_lock] [139783589922560] Time since create 1.9156 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,975 DEBUG [tests.test_lock] [139783598315264] creating resource, will take 2.500000 sec +2023-09-27 20:00:02,979 DEBUG [tests.test_lock] [139783547959040] Time since create 2.0360 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,995 DEBUG [tests.test_lock] [139783556351744] Time since create 2.0521 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,999 DEBUG [tests.test_lock] [139783066089216] Time since create 2.0561 max stale time 5.115, total waited 11 +2023-09-27 20:00:02,999 DEBUG [tests.test_lock] [139783049303808] Time since create 2.0565 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,000 DEBUG [tests.test_lock] [139783564744448] Time since create 2.0573 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,011 DEBUG [tests.test_lock] [139783573137152] Time since create 2.0681 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,011 DEBUG [tests.test_lock] [139783581529856] Time since create 2.0684 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,012 DEBUG [tests.test_lock] [139783057696512] Time since create 2.0686 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,012 DEBUG [tests.test_lock] [139783589922560] Time since create 2.0688 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,135 DEBUG [tests.test_lock] [139783547959040] Time since create 2.1921 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,151 DEBUG [tests.test_lock] [139783556351744] Time since create 2.2081 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,155 DEBUG [tests.test_lock] [139783066089216] Time since create 2.2121 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,155 DEBUG [tests.test_lock] [139783049303808] Time since create 2.2125 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,159 DEBUG [tests.test_lock] [139783564744448] Time since create 2.2159 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,167 DEBUG [tests.test_lock] [139783573137152] Time since create 2.2241 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,167 DEBUG [tests.test_lock] [139783581529856] Time since create 2.2245 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,168 DEBUG [tests.test_lock] [139783057696512] Time since create 2.2247 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,168 DEBUG [tests.test_lock] [139783589922560] Time since create 2.2249 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,295 DEBUG [tests.test_lock] [139783547959040] Time since create 2.3521 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,307 DEBUG [tests.test_lock] [139783556351744] Time since create 2.3641 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,311 DEBUG [tests.test_lock] [139783066089216] Time since create 2.3681 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,311 DEBUG [tests.test_lock] [139783049303808] Time since create 2.3684 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,315 DEBUG [tests.test_lock] [139783564744448] Time since create 2.3720 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,322 DEBUG [tests.test_lock] [139783573137152] Time since create 2.3787 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,322 DEBUG [tests.test_lock] [139783581529856] Time since create 2.3791 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,322 DEBUG [tests.test_lock] [139783057696512] Time since create 2.3793 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,322 DEBUG [tests.test_lock] [139783589922560] Time since create 2.3794 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,450 DEBUG [tests.test_lock] [139783547959040] Time since create 2.5068 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,463 DEBUG [tests.test_lock] [139783556351744] Time since create 2.5201 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,467 DEBUG [tests.test_lock] [139783066089216] Time since create 2.5241 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,467 DEBUG [tests.test_lock] [139783049303808] Time since create 2.5245 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,472 DEBUG [tests.test_lock] [139783564744448] Time since create 2.5288 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,479 DEBUG [tests.test_lock] [139783573137152] Time since create 2.5361 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,479 DEBUG [tests.test_lock] [139783581529856] Time since create 2.5364 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,480 DEBUG [tests.test_lock] [139783057696512] Time since create 2.5365 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,480 DEBUG [tests.test_lock] [139783589922560] Time since create 2.5367 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,607 DEBUG [tests.test_lock] [139783547959040] Time since create 2.6641 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,619 DEBUG [tests.test_lock] [139783556351744] Time since create 2.6761 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,623 DEBUG [tests.test_lock] [139783066089216] Time since create 2.6801 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,623 DEBUG [tests.test_lock] [139783049303808] Time since create 2.6804 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,627 DEBUG [tests.test_lock] [139783564744448] Time since create 2.6840 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,635 DEBUG [tests.test_lock] [139783573137152] Time since create 2.6921 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,635 DEBUG [tests.test_lock] [139783581529856] Time since create 2.6924 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,636 DEBUG [tests.test_lock] [139783057696512] Time since create 2.6926 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,636 DEBUG [tests.test_lock] [139783589922560] Time since create 2.6927 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,763 DEBUG [tests.test_lock] [139783547959040] Time since create 2.8201 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,775 DEBUG [tests.test_lock] [139783556351744] Time since create 2.8321 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,779 DEBUG [tests.test_lock] [139783066089216] Time since create 2.8361 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,779 DEBUG [tests.test_lock] [139783049303808] Time since create 2.8364 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,783 DEBUG [tests.test_lock] [139783564744448] Time since create 2.8400 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,787 DEBUG [tests.test_lock] [139783573137152] Time since create 2.8441 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,787 DEBUG [tests.test_lock] [139783581529856] Time since create 2.8443 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,787 DEBUG [tests.test_lock] [139783057696512] Time since create 2.8445 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,788 DEBUG [tests.test_lock] [139783589922560] Time since create 2.8446 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,919 DEBUG [tests.test_lock] [139783547959040] Time since create 2.9761 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,931 DEBUG [tests.test_lock] [139783556351744] Time since create 2.9881 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,939 DEBUG [tests.test_lock] [139783066089216] Time since create 2.9961 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,939 DEBUG [tests.test_lock] [139783049303808] Time since create 2.9964 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,943 DEBUG [tests.test_lock] [139783564744448] Time since create 3.0001 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,943 DEBUG [tests.test_lock] [139783573137152] Time since create 3.0004 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,944 DEBUG [tests.test_lock] [139783581529856] Time since create 3.0005 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,944 DEBUG [tests.test_lock] [139783057696512] Time since create 3.0007 max stale time 5.115, total waited 11 +2023-09-27 20:00:03,944 DEBUG [tests.test_lock] [139783589922560] Time since create 3.0008 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,079 DEBUG [tests.test_lock] [139783547959040] Time since create 3.1361 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,087 DEBUG [tests.test_lock] [139783556351744] Time since create 3.1441 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,099 DEBUG [tests.test_lock] [139783564744448] Time since create 3.1561 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,099 DEBUG [tests.test_lock] [139783573137152] Time since create 3.1565 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,100 DEBUG [tests.test_lock] [139783581529856] Time since create 3.1567 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,100 DEBUG [tests.test_lock] [139783057696512] Time since create 3.1568 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,100 DEBUG [tests.test_lock] [139783589922560] Time since create 3.1570 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,103 DEBUG [tests.test_lock] [139783066089216] Time since create 3.1605 max stale time 5.115, total waited 11 +2023-09-27 20:00:04,104 DEBUG [tests.test_lock] [139783049303808] Time since create 3.1608 max stale time 5.115, total waited 11 +2023-09-27 20:00:05,491 DEBUG [tests.test_lock] [139783598315264] finished creating resource +2023-09-27 20:00:05,491 DEBUG [tests.test_lock] [139783598315264] Time since create 0.0004 max stale time 5.115, total waited 12 +2023-09-27 20:00:05,651 DEBUG [tests.test_lock] [139783598315264] Time since create 0.1600 max stale time 5.115, total waited 12 +2023-09-27 20:00:05,807 DEBUG [tests.test_lock] [139783598315264] Time since create 0.3160 max stale time 5.115, total waited 12 +2023-09-27 20:00:05,967 DEBUG [tests.test_lock] [139783598315264] Time since create 0.4760 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,123 DEBUG [tests.test_lock] [139783598315264] Time since create 0.6320 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,279 DEBUG [tests.test_lock] [139783598315264] Time since create 0.7880 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,435 DEBUG [tests.test_lock] [139783598315264] Time since create 0.9440 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,591 DEBUG [tests.test_lock] [139783598315264] Time since create 1.1000 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,751 DEBUG [tests.test_lock] [139783598315264] Time since create 1.2600 max stale time 5.115, total waited 12 +2023-09-27 20:00:06,907 DEBUG [tests.test_lock] [139783598315264] Time since create 1.4160 max stale time 5.115, total waited 12 +2023-09-27 20:00:07,063 DEBUG [tests.test_lock] [139783598315264] Time since create 1.5720 max stale time 5.115, total waited 12 +2023-09-27 20:00:07,219 DEBUG [tests.test_lock] [139783598315264] Time since create 1.7280 max stale time 5.115, total waited 12 +2023-09-27 20:00:07,375 DEBUG [tests.test_lock] [139783598315264] Time since create 1.8840 max stale time 5.115, total waited 12 +2023-09-27 20:00:07,531 DEBUG [tests.test_lock] [139783598315264] creating resource, will take 2.500000 sec +2023-09-27 20:00:10,043 DEBUG [tests.test_lock] [139783598315264] finished creating resource +2023-09-27 20:00:10,043 DEBUG [tests.test_lock] [139783598315264] Time since create 0.0004 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,199 DEBUG [tests.test_lock] [139783598315264] Time since create 0.1560 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,355 DEBUG [tests.test_lock] [139783598315264] Time since create 0.3120 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,511 DEBUG [tests.test_lock] [139783598315264] Time since create 0.4680 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,667 DEBUG [tests.test_lock] [139783598315264] Time since create 0.6240 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,823 DEBUG [tests.test_lock] [139783598315264] Time since create 0.7801 max stale time 5.115, total waited 13 +2023-09-27 20:00:10,979 DEBUG [tests.test_lock] [139783598315264] Time since create 0.9360 max stale time 5.115, total waited 13 +2023-09-27 20:00:11,135 DEBUG [tests.test_lock] [139783598315264] Time since create 1.0921 max stale time 5.115, total waited 13 +2023-09-27 20:00:11,291 DEBUG [tests.test_lock] [139783598315264] Time since create 1.2480 max stale time 5.115, total waited 13 +2023-09-27 20:00:11,447 DEBUG [tests.test_lock] [139783598315264] Time since create 1.4040 max stale time 5.115, total waited 13 +2023-09-27 20:00:11,603 DEBUG [tests.test_lock] [139783598315264] Time since create 1.5601 max stale time 5.115, total waited 13 +2023-09-27 20:00:11,763 INFO [tests.test_lock] [139783626848064] Test Summary +2023-09-27 20:00:11,763 INFO [tests.test_lock] [139783626848064] num threads: 10; expiretime: 2; creation_time: 2.5; num_usages: 50; usage_time: 0.05; delay_time: 0.1 +2023-09-27 20:00:11,764 INFO [tests.test_lock] [139783626848064] cache expire time: None; unsafe cache: False +2023-09-27 20:00:11,764 INFO [tests.test_lock] [139783626848064] Estimated run time 15.68 actual run time 17.89 +2023-09-27 20:00:11,764 INFO [tests.test_lock] [139783626848064] Effective expiretime (min(cache_exp_time, exptime)) 2 +2023-09-27 20:00:11,764 INFO [tests.test_lock] [139783626848064] Expected slow waits 14.0, Total slow waits 13 +2023-09-27 20:00:11,764 INFO [tests.test_lock] [139783626848064] Total generations 4 Max generations expected 5.0 +------------------------------ Captured log call ------------------------------- +test_lock.py 122 DEBUG creating resource, will take 2.500000 sec +test_lock.py 140 DEBUG finished creating resource +test_lock.py 73 DEBUG Time since create 0.0004 max stale time 5.115, total waited 1 +test_lock.py 73 DEBUG Time since create 0.0041 max stale time 5.115, total waited 2 +test_lock.py 73 DEBUG Time since create 0.0045 max stale time 5.115, total waited 3 +test_lock.py 73 DEBUG Time since create 0.0046 max stale time 5.115, total waited 4 +test_lock.py 73 DEBUG Time since create 0.0048 max stale time 5.115, total waited 5 +test_lock.py 73 DEBUG Time since create 0.0081 max stale time 5.115, total waited 6 +test_lock.py 73 DEBUG Time since create 0.0121 max stale time 5.115, total waited 7 +test_lock.py 73 DEBUG Time since create 0.0161 max stale time 5.115, total waited 8 +test_lock.py 73 DEBUG Time since create 0.0201 max stale time 5.115, total waited 9 +test_lock.py 73 DEBUG Time since create 0.0246 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1561 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1600 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1604 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1605 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1607 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1640 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1680 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1720 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1760 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.1800 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3121 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3125 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3160 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3200 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3204 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3212 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3280 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3320 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3361 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.3370 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4677 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4681 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4720 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4760 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4801 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4804 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4836 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4880 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4921 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.4960 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6240 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6244 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6280 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6320 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6360 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6364 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6385 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6440 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6481 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.6520 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7801 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7804 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7840 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7880 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7920 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7924 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.7933 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.8000 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.8029 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.8080 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9361 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9364 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9401 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9425 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9463 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9467 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9480 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9560 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9600 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 0.9605 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.0961 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1000 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1004 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1006 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1008 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1041 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1080 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1120 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1161 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.1200 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2521 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2601 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2604 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2606 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2607 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2641 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2680 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2721 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2741 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.2760 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4080 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4160 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4164 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4165 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4167 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4229 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4236 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4281 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4320 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.4360 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5640 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5714 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5718 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5720 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5722 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5800 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5840 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5880 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5908 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.5960 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7185 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7280 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7284 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7286 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7287 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7360 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7404 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7440 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7480 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.7520 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8749 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8920 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8929 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8933 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8934 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8936 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.8943 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.9000 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.9038 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 1.9048 max stale time 5.115, total waited 10 +test_lock.py 122 DEBUG creating resource, will take 2.500000 sec +test_lock.py 73 DEBUG Time since create 2.0481 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0484 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0604 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0485 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0486 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0487 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0561 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0681 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.0721 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2081 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2201 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2240 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2251 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2254 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2256 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2257 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2258 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.2280 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3681 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3751 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3802 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3807 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3809 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3811 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3812 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3801 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.3880 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5241 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5320 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5361 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5365 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5367 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5368 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5370 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5401 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.5441 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6770 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6881 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6891 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6894 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6896 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6897 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6898 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6960 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.6985 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8281 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8441 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8447 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8451 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8452 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8454 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8458 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8521 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.8561 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.9841 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 2.9961 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0001 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0004 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0006 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0008 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0014 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0081 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.0160 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1361 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1510 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1601 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1604 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1605 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1607 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1608 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1641 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.1681 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.2881 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3048 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3161 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3165 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3166 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3168 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3170 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3201 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.3208 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4425 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4641 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4721 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4724 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4727 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4728 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4730 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4761 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.4801 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6001 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6200 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6281 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6284 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6286 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6287 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6289 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6323 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.6361 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7601 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7761 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7841 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7844 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7845 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7846 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7848 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7890 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.7921 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9200 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9321 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9401 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9405 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9407 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9408 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9410 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9441 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 3.9480 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0761 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0921 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0961 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0965 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0967 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0969 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.0971 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.1001 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.1041 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2321 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2481 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2484 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2563 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2565 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2567 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2485 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2601 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.2605 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.3881 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4081 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4123 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4127 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4128 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4130 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4132 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4161 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.4200 max stale time 5.115, total waited 10 +test_lock.py 73 DEBUG Time since create 4.5441 max stale time 5.115, total waited 10 +test_lock.py 140 DEBUG finished creating resource +test_lock.py 73 DEBUG Time since create 0.0003 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0161 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0187 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0191 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0192 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0196 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0198 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0217 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.0323 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1520 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1560 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1721 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1760 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1764 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1765 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1767 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1769 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1801 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.1841 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3046 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3121 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3281 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3321 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3324 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3326 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3327 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3328 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3360 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.3367 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.4641 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.4681 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.4841 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.4921 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.4925 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.5040 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.5044 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.5045 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.5047 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.5048 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6201 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6279 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6401 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6450 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6454 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6600 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6605 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6607 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6608 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.6610 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.7760 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.7841 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8001 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8005 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8041 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8160 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8164 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8166 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8167 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.8170 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9321 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9400 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9560 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9564 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9601 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9721 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9725 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9726 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9728 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 0.9729 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.0880 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.0961 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1160 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1200 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1204 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1276 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1280 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1282 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1284 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.1285 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2440 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2481 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2706 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2727 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2732 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2840 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2844 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2845 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2847 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.2849 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4001 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4041 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4280 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4321 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4324 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4400 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4404 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4405 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4407 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.4408 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5560 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5601 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5840 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5881 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5885 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.5920 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.6000 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.6004 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.6005 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.6007 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7121 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7160 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7357 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7421 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7425 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7480 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7561 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7565 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7567 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.7568 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.8720 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.8760 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.8920 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.8984 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.8988 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.9041 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.9150 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.9153 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.9155 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 1.9156 max stale time 5.115, total waited 11 +test_lock.py 122 DEBUG creating resource, will take 2.500000 sec +test_lock.py 73 DEBUG Time since create 2.0360 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0521 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0561 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0565 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0573 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0681 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0684 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0686 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.0688 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.1921 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2081 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2121 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2125 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2159 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2241 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2245 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2247 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.2249 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3521 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3641 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3681 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3684 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3720 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3787 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3791 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3793 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.3794 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5068 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5201 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5241 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5245 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5288 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5361 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5364 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5365 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.5367 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6641 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6761 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6801 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6804 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6840 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6921 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6924 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6926 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.6927 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8201 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8321 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8361 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8364 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8400 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8441 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8443 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8445 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.8446 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.9761 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.9881 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.9961 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 2.9964 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.0001 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.0004 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.0005 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.0007 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.0008 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1361 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1441 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1561 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1565 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1567 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1568 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1570 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1605 max stale time 5.115, total waited 11 +test_lock.py 73 DEBUG Time since create 3.1608 max stale time 5.115, total waited 11 +test_lock.py 140 DEBUG finished creating resource +test_lock.py 73 DEBUG Time since create 0.0004 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.1600 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.3160 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.4760 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.6320 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.7880 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 0.9440 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.1000 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.2600 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.4160 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.5720 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.7280 max stale time 5.115, total waited 12 +test_lock.py 73 DEBUG Time since create 1.8840 max stale time 5.115, total waited 12 +test_lock.py 122 DEBUG creating resource, will take 2.500000 sec +test_lock.py 140 DEBUG finished creating resource +test_lock.py 73 DEBUG Time since create 0.0004 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.1560 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.3120 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.4680 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.6240 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.7801 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 0.9360 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 1.0921 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 1.2480 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 1.4040 max stale time 5.115, total waited 13 +test_lock.py 73 DEBUG Time since create 1.5601 max stale time 5.115, total waited 13 +test_lock.py 234 INFO Test Summary +test_lock.py 240 INFO num threads: 10; expiretime: 2; creation_time: 2.5; num_usages: 50; usage_time: 0.05; delay_time: 0.1 +test_lock.py 244 INFO cache expire time: None; unsafe cache: False +test_lock.py 247 INFO Estimated run time 15.68 actual run time 17.89 +test_lock.py 250 INFO Effective expiretime (min(cache_exp_time, exptime)) 2 +test_lock.py 253 INFO Expected slow waits 14.0, Total slow waits 13 +test_lock.py 256 INFO Total generations 4 Max generations expected 5.0 +=========================== short test summary info ============================ +FAIL tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_long_create =============================== warnings summary =============================== /usr/lib/python2.7/dist-packages/_pytest/config/findpaths.py:44 /usr/lib/python2.7/dist-packages/_pytest/config/findpaths.py:44: RemovedInPytest4Warning: [pytest] section in setup.cfg files is deprecated, use [tool:pytest] instead. config=config, -- Docs: https://docs.pytest.org/en/latest/warnings.html -============ 288 passed, 208 skipped, 1 warnings in 224.03 seconds ============= -PYTHONPATH=. python3 -m pytest -============================= test session starts ============================== -platform linux -- Python 3.7.3, pytest-3.10.1, py-1.7.0, pluggy-0.8.0 -- /usr/bin/python3 -cachedir: .pytest_cache -rootdir: /build/python-dogpile.cache-0.6.2, inifile: setup.cfg -collecting ... collected 496 items - -tests/test_backgrounding.py::TestAsyncRunner::test_async_release PASSED [ 0%] -tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_long_create PASSED [ 0%] -tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_registry_safe_cache_quick PASSED [ 0%] -tests/test_lock.py::ConcurrencyTest::test_get_value_plus_created_registry_unsafe_cache PASSED [ 0%] -tests/test_lock.py::ConcurrencyTest::test_quick PASSED [ 1%] -tests/test_lock.py::ConcurrencyTest::test_return_while_in_progress PASSED [ 1%] -tests/test_lock.py::ConcurrencyTest::test_slow PASSED [ 1%] -tests/test_utils.py::UtilsTest::test_coerce_string_conf PASSED [ 1%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_backend_delete PASSED [ 1%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_backend_delete_nothing PASSED [ 2%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_backend_get_nothing PASSED [ 2%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_backend_set_get_value PASSED [ 2%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_decorated_fn_functionality PASSED [ 2%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_exploding_value_fn PASSED [ 2%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_creator PASSED [ 3%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_delete PASSED [ 3%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_delete_multiple PASSED [ 3%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_expire PASSED [ 3%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_get_empty_multiple PASSED [ 3%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_get_multiple_values PASSED [ 4%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_get_nothing_multiple PASSED [ 4%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_get_zero_multiple_values PASSED [ 4%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_set_get_nothing PASSED [ 4%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_set_get_value PASSED [ 4%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_set_multiple_values PASSED [ 5%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_set_zero_multiple_values PASSED [ 5%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_region_set_zero_multiple_values_w_decorator PASSED [ 5%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_threaded_dogpile PASSED [ 5%] -tests/cache/test_dbm_backend.py::DBMBackendTest::test_threaded_get_multi PASSED [ 5%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_backend_delete PASSED [ 6%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_backend_delete_nothing PASSED [ 6%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_backend_get_nothing PASSED [ 6%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_backend_set_get_value PASSED [ 6%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_decorated_fn_functionality PASSED [ 6%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_exploding_value_fn PASSED [ 7%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_creator PASSED [ 7%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_delete PASSED [ 7%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_delete_multiple PASSED [ 7%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_expire PASSED [ 7%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_get_empty_multiple PASSED [ 8%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_get_multiple_values PASSED [ 8%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_get_nothing_multiple PASSED [ 8%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_get_zero_multiple_values PASSED [ 8%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_set_get_nothing PASSED [ 8%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_set_get_value PASSED [ 9%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_set_multiple_values PASSED [ 9%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_set_zero_multiple_values PASSED [ 9%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_region_set_zero_multiple_values_w_decorator PASSED [ 9%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_threaded_dogpile PASSED [ 9%] -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_threaded_get_multi PASSED [ 10%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_backend_delete PASSED [ 10%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_backend_delete_nothing PASSED [ 10%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_backend_get_nothing PASSED [ 10%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_backend_set_get_value PASSED [ 10%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_decorated_fn_functionality PASSED [ 11%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_exploding_value_fn PASSED [ 11%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_creator PASSED [ 11%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_delete PASSED [ 11%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_delete_multiple PASSED [ 11%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_expire PASSED [ 12%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_get_empty_multiple PASSED [ 12%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_get_multiple_values PASSED [ 12%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_get_nothing_multiple PASSED [ 12%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_get_zero_multiple_values PASSED [ 12%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_set_get_nothing PASSED [ 13%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_set_get_value PASSED [ 13%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_set_multiple_values PASSED [ 13%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_set_zero_multiple_values PASSED [ 13%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_region_set_zero_multiple_values_w_decorator PASSED [ 13%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_threaded_dogpile PASSED [ 14%] -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_threaded_get_multi PASSED [ 14%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_mutex PASSED [ 14%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_mutex_reentrant_across_keys PASSED [ 14%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_mutex_threaded PASSED [ 14%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_reentrant_dogpile PASSED [ 15%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_release_assertion_key PASSED [ 15%] -tests/cache/test_dbm_backend.py::DBMMutexFileTest::test_release_assertion_thread PASSED [ 15%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_mutex PASSED [ 15%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_mutex_reentrant_across_keys PASSED [ 15%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_mutex_threaded PASSED [ 16%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_reentrant_dogpile PASSED [ 16%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_release_assertion_key PASSED [ 16%] -tests/cache/test_dbm_backend.py::DBMMutexConditionTest::test_release_assertion_thread PASSED [ 16%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator PASSED [ 16%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_custom_expire PASSED [ 17%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_expire_callable PASSED [ 17%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_expire_callable_zero PASSED [ 17%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_key_generator PASSED [ 17%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_key_generator_multi PASSED [ 17%] -tests/cache/test_decorator.py::DecoratorTest::test_decorator_namespace PASSED [ 18%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_expire PASSED [ 18%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_get PASSED [ 18%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_get_multi PASSED [ 18%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_refresh PASSED [ 18%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_refresh_multi PASSED [ 19%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_set PASSED [ 19%] -tests/cache/test_decorator.py::DecoratorTest::test_explicit_set_multi PASSED [ 19%] -tests/cache/test_decorator.py::KeyGenerationTest::test_default_keygen_kwargs_raises_value_error PASSED [ 19%] -tests/cache/test_decorator.py::KeyGenerationTest::test_key_isnt_unicode_bydefault PASSED [ 19%] -tests/cache/test_decorator.py::KeyGenerationTest::test_keygen_fn PASSED [ 20%] -tests/cache/test_decorator.py::KeyGenerationTest::test_keygen_fn_namespace PASSED [ 20%] -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn PASSED [ 20%] -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn_all_defaults PASSED [ 20%] -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn_with_defaults_and_positional PASSED [ 20%] -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_keygen_fn_namespace PASSED [ 21%] -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kwgen_key_isnt_unicode_bydefault PASSED [ 21%] -tests/cache/test_decorator.py::KeyGenerationTest::test_multi_keygen_fn PASSED [ 21%] -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key PASSED [ 21%] -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_by_default PASSED [ 21%] -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_by_default_kwarg_generator PASSED [ 22%] -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_kwarg_generator PASSED [ 22%] -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_multi PASSED [ 22%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_cache_arg PASSED [ 22%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi PASSED [ 22%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict PASSED [ 23%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict_keys_missing PASSED [ 23%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict_keys_missing_existing_cache_fn PASSED [ 23%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_namespace PASSED [ 23%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_original_fn_set PASSED [ 23%] -tests/cache/test_decorator.py::CacheDecoratorTest::test_reentrant_call PASSED [ 24%] -tests/cache/test_mako.py::MakoTest::test_entry_point PASSED [ 24%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_backend_delete SKIPPED [ 24%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_backend_delete_nothing SKIPPED [ 24%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_backend_get_nothing SKIPPED [ 25%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_backend_set_get_value SKIPPED [ 25%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_decorated_fn_functionality SKIPPED [ 25%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_exploding_value_fn SKIPPED [ 25%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_creator SKIPPED [ 25%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_delete SKIPPED [ 26%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_delete_multiple SKIPPED [ 26%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_expire SKIPPED [ 26%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_get_empty_multiple SKIPPED [ 26%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_get_multiple_values SKIPPED [ 26%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_get_nothing_multiple SKIPPED [ 27%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_get_zero_multiple_values SKIPPED [ 27%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_set_get_nothing SKIPPED [ 27%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_set_get_value SKIPPED [ 27%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_set_multiple_values SKIPPED [ 27%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_set_zero_multiple_values SKIPPED [ 28%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 28%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_threaded_dogpile SKIPPED [ 28%] -tests/cache/test_memcached_backend.py::PylibmcTest::test_threaded_get_multi SKIPPED [ 28%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_backend_delete SKIPPED [ 28%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_backend_delete_nothing SKIPPED [ 29%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_backend_get_nothing SKIPPED [ 29%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_backend_set_get_value SKIPPED [ 29%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_decorated_fn_functionality SKIPPED [ 29%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_exploding_value_fn SKIPPED [ 29%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_creator SKIPPED [ 30%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_delete SKIPPED [ 30%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_delete_multiple SKIPPED [ 30%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_expire SKIPPED [ 30%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_get_empty_multiple SKIPPED [ 30%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_get_multiple_values SKIPPED [ 31%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_get_nothing_multiple SKIPPED [ 31%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_get_zero_multiple_values SKIPPED [ 31%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_set_get_nothing SKIPPED [ 31%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_set_get_value SKIPPED [ 31%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_set_multiple_values SKIPPED [ 32%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_set_zero_multiple_values SKIPPED [ 32%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 32%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_threaded_dogpile SKIPPED [ 32%] -tests/cache/test_memcached_backend.py::PylibmcDistributedTest::test_threaded_get_multi SKIPPED [ 32%] -tests/cache/test_memcached_backend.py::PylibmcDistributedMutexTest::test_mutex SKIPPED [ 33%] -tests/cache/test_memcached_backend.py::PylibmcDistributedMutexTest::test_mutex_reentrant_across_keys SKIPPED [ 33%] -tests/cache/test_memcached_backend.py::PylibmcDistributedMutexTest::test_mutex_threaded SKIPPED [ 33%] -tests/cache/test_memcached_backend.py::PylibmcDistributedMutexTest::test_reentrant_dogpile SKIPPED [ 33%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_backend_delete SKIPPED [ 33%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_backend_delete_nothing SKIPPED [ 34%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_backend_get_nothing SKIPPED [ 34%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_backend_set_get_value SKIPPED [ 34%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_decorated_fn_functionality SKIPPED [ 34%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_exploding_value_fn SKIPPED [ 34%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_mutex_threaded SKIPPED [ 35%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_mutex_threaded_dogpile SKIPPED [ 35%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_creator SKIPPED [ 35%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_delete SKIPPED [ 35%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_delete_multiple SKIPPED [ 35%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_expire SKIPPED [ 36%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_get_empty_multiple SKIPPED [ 36%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_get_multiple_values SKIPPED [ 36%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_get_nothing_multiple SKIPPED [ 36%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_get_zero_multiple_values SKIPPED [ 36%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_set_get_nothing SKIPPED [ 37%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_set_get_value SKIPPED [ 37%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_set_multiple_values SKIPPED [ 37%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_set_zero_multiple_values SKIPPED [ 37%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 37%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_threaded_dogpile SKIPPED [ 38%] -tests/cache/test_memcached_backend.py::BMemcachedTest::test_threaded_get_multi SKIPPED [ 38%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_backend_delete SKIPPED [ 38%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_backend_delete_nothing SKIPPED [ 38%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_backend_get_nothing SKIPPED [ 38%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_backend_set_get_value SKIPPED [ 39%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_decorated_fn_functionality SKIPPED [ 39%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_exploding_value_fn SKIPPED [ 39%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_mutex_threaded SKIPPED [ 39%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_mutex_threaded_dogpile SKIPPED [ 39%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_creator SKIPPED [ 40%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_delete SKIPPED [ 40%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_delete_multiple SKIPPED [ 40%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_expire SKIPPED [ 40%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_get_empty_multiple SKIPPED [ 40%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_get_multiple_values SKIPPED [ 41%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_get_nothing_multiple SKIPPED [ 41%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_get_zero_multiple_values SKIPPED [ 41%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_set_get_nothing SKIPPED [ 41%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_set_get_value SKIPPED [ 41%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_set_multiple_values SKIPPED [ 42%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_set_zero_multiple_values SKIPPED [ 42%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 42%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_threaded_dogpile SKIPPED [ 42%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedWithTimeoutTest::test_threaded_get_multi SKIPPED [ 42%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_backend_delete SKIPPED [ 43%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_backend_delete_nothing SKIPPED [ 43%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_backend_get_nothing SKIPPED [ 43%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_backend_set_get_value SKIPPED [ 43%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_decorated_fn_functionality SKIPPED [ 43%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_exploding_value_fn SKIPPED [ 44%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_mutex_threaded SKIPPED [ 44%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_mutex_threaded_dogpile SKIPPED [ 44%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_creator SKIPPED [ 44%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_delete SKIPPED [ 44%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_delete_multiple SKIPPED [ 45%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_expire SKIPPED [ 45%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_get_empty_multiple SKIPPED [ 45%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_get_multiple_values SKIPPED [ 45%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_get_nothing_multiple SKIPPED [ 45%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_get_zero_multiple_values SKIPPED [ 46%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_set_get_nothing SKIPPED [ 46%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_set_get_value SKIPPED [ 46%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_set_multiple_values SKIPPED [ 46%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_set_zero_multiple_values SKIPPED [ 46%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 47%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_threaded_dogpile SKIPPED [ 47%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedTest::test_threaded_get_multi SKIPPED [ 47%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_mutex SKIPPED [ 47%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_mutex_reentrant_across_keys SKIPPED [ 47%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_mutex_threaded SKIPPED [ 48%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_mutex_threaded_dogpile SKIPPED [ 48%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_reentrant_dogpile SKIPPED [ 48%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_threaded_dogpile SKIPPED [ 48%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexTest::test_threaded_get_multi SKIPPED [ 48%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_mutex SKIPPED [ 49%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_mutex_reentrant_across_keys SKIPPED [ 49%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_mutex_threaded SKIPPED [ 49%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_mutex_threaded_dogpile SKIPPED [ 49%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_reentrant_dogpile SKIPPED [ 50%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_threaded_dogpile SKIPPED [ 50%] -tests/cache/test_memcached_backend.py::BMemcachedDistributedMutexWithTimeoutTest::test_threaded_get_multi SKIPPED [ 50%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_backend_delete SKIPPED [ 50%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_backend_delete_nothing SKIPPED [ 50%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_backend_get_nothing SKIPPED [ 51%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_backend_set_get_value SKIPPED [ 51%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_decorated_fn_functionality SKIPPED [ 51%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_exploding_value_fn SKIPPED [ 51%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_creator SKIPPED [ 51%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_delete SKIPPED [ 52%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_delete_multiple SKIPPED [ 52%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_expire SKIPPED [ 52%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_get_empty_multiple SKIPPED [ 52%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_get_multiple_values SKIPPED [ 52%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_get_nothing_multiple SKIPPED [ 53%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_get_zero_multiple_values SKIPPED [ 53%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_set_get_nothing SKIPPED [ 53%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_set_get_value SKIPPED [ 53%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_set_multiple_values SKIPPED [ 53%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_set_zero_multiple_values SKIPPED [ 54%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 54%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_threaded_dogpile SKIPPED [ 54%] -tests/cache/test_memcached_backend.py::MemcachedTest::test_threaded_get_multi SKIPPED [ 54%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_backend_delete SKIPPED [ 54%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_backend_delete_nothing SKIPPED [ 55%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_backend_get_nothing SKIPPED [ 55%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_backend_set_get_value SKIPPED [ 55%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_decorated_fn_functionality SKIPPED [ 55%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_exploding_value_fn SKIPPED [ 55%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_creator SKIPPED [ 56%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_delete SKIPPED [ 56%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_delete_multiple SKIPPED [ 56%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_expire SKIPPED [ 56%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_get_empty_multiple SKIPPED [ 56%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_get_multiple_values SKIPPED [ 57%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_get_nothing_multiple SKIPPED [ 57%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_get_zero_multiple_values SKIPPED [ 57%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_set_get_nothing SKIPPED [ 57%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_set_get_value SKIPPED [ 57%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_set_multiple_values SKIPPED [ 58%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_set_zero_multiple_values SKIPPED [ 58%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 58%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_threaded_dogpile SKIPPED [ 58%] -tests/cache/test_memcached_backend.py::MemcachedDistributedTest::test_threaded_get_multi SKIPPED [ 58%] -tests/cache/test_memcached_backend.py::MemcachedDistributedMutexTest::test_mutex SKIPPED [ 59%] -tests/cache/test_memcached_backend.py::MemcachedDistributedMutexTest::test_mutex_reentrant_across_keys SKIPPED [ 59%] -tests/cache/test_memcached_backend.py::MemcachedDistributedMutexTest::test_mutex_threaded SKIPPED [ 59%] -tests/cache/test_memcached_backend.py::MemcachedDistributedMutexTest::test_reentrant_dogpile SKIPPED [ 59%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_behaviors PASSED [ 59%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_binary_flag PASSED [ 60%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_no_set_args PASSED [ 60%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_set_min_compress_len PASSED [ 60%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_set_time PASSED [ 60%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_url_list PASSED [ 60%] -tests/cache/test_memcached_backend.py::PylibmcArgsTest::test_url_scalar PASSED [ 61%] -tests/cache/test_memcached_backend.py::MemcachedArgstest::test_set_min_compress_len PASSED [ 61%] -tests/cache/test_memcached_backend.py::MemcachedArgstest::test_set_time PASSED [ 61%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_backend_delete PASSED [ 61%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_backend_delete_nothing PASSED [ 61%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_backend_get_nothing PASSED [ 62%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_backend_set_get_value PASSED [ 62%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_decorated_fn_functionality PASSED [ 62%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_exploding_value_fn PASSED [ 62%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_creator PASSED [ 62%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_delete PASSED [ 63%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_delete_multiple PASSED [ 63%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_expire PASSED [ 63%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_get_empty_multiple PASSED [ 63%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_get_multiple_values PASSED [ 63%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_get_nothing_multiple PASSED [ 64%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_get_zero_multiple_values PASSED [ 64%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_set_get_nothing PASSED [ 64%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_set_get_value PASSED [ 64%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_set_multiple_values PASSED [ 64%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_set_zero_multiple_values PASSED [ 65%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_region_set_zero_multiple_values_w_decorator PASSED [ 65%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_threaded_dogpile PASSED [ 65%] -tests/cache/test_memory_backend.py::MemoryBackendTest::test_threaded_get_multi PASSED [ 65%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_backend_delete PASSED [ 65%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_backend_delete_nothing PASSED [ 66%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_backend_get_nothing PASSED [ 66%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_backend_set_get_value PASSED [ 66%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_decorated_fn_functionality PASSED [ 66%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_exploding_value_fn PASSED [ 66%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_creator PASSED [ 67%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_delete PASSED [ 67%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_delete_multiple PASSED [ 67%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_expire PASSED [ 67%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_get_empty_multiple PASSED [ 67%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_get_multiple_values PASSED [ 68%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_get_nothing_multiple PASSED [ 68%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_get_zero_multiple_values PASSED [ 68%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_set_get_nothing PASSED [ 68%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_set_get_value PASSED [ 68%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_set_multiple_values PASSED [ 69%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_set_zero_multiple_values PASSED [ 69%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_region_set_zero_multiple_values_w_decorator PASSED [ 69%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_threaded_dogpile PASSED [ 69%] -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_threaded_get_multi PASSED [ 69%] -tests/cache/test_null_backend.py::NullBackendTest::test_decorator PASSED [ 70%] -tests/cache/test_null_backend.py::NullBackendTest::test_delete PASSED [ 70%] -tests/cache/test_null_backend.py::NullBackendTest::test_delete_multi PASSED [ 70%] -tests/cache/test_null_backend.py::NullBackendTest::test_get PASSED [ 70%] -tests/cache/test_null_backend.py::NullBackendTest::test_get_multi PASSED [ 70%] -tests/cache/test_null_backend.py::NullBackendTest::test_mutex PASSED [ 71%] -tests/cache/test_null_backend.py::NullBackendTest::test_mutex_doesnt_actually_lock PASSED [ 71%] -tests/cache/test_null_backend.py::NullBackendTest::test_set PASSED [ 71%] -tests/cache/test_null_backend.py::NullBackendTest::test_set_multi PASSED [ 71%] -tests/cache/test_redis_backend.py::RedisTest::test_backend_delete SKIPPED [ 71%] -tests/cache/test_redis_backend.py::RedisTest::test_backend_delete_nothing SKIPPED [ 72%] -tests/cache/test_redis_backend.py::RedisTest::test_backend_get_nothing SKIPPED [ 72%] -tests/cache/test_redis_backend.py::RedisTest::test_backend_set_get_value SKIPPED [ 72%] -tests/cache/test_redis_backend.py::RedisTest::test_decorated_fn_functionality SKIPPED [ 72%] -tests/cache/test_redis_backend.py::RedisTest::test_exploding_value_fn SKIPPED [ 72%] -tests/cache/test_redis_backend.py::RedisTest::test_region_creator SKIPPED [ 73%] -tests/cache/test_redis_backend.py::RedisTest::test_region_delete SKIPPED [ 73%] -tests/cache/test_redis_backend.py::RedisTest::test_region_delete_multiple SKIPPED [ 73%] -tests/cache/test_redis_backend.py::RedisTest::test_region_expire SKIPPED [ 73%] -tests/cache/test_redis_backend.py::RedisTest::test_region_get_empty_multiple SKIPPED [ 73%] -tests/cache/test_redis_backend.py::RedisTest::test_region_get_multiple_values SKIPPED [ 74%] -tests/cache/test_redis_backend.py::RedisTest::test_region_get_nothing_multiple SKIPPED [ 74%] -tests/cache/test_redis_backend.py::RedisTest::test_region_get_zero_multiple_values SKIPPED [ 74%] -tests/cache/test_redis_backend.py::RedisTest::test_region_set_get_nothing SKIPPED [ 74%] -tests/cache/test_redis_backend.py::RedisTest::test_region_set_get_value SKIPPED [ 75%] -tests/cache/test_redis_backend.py::RedisTest::test_region_set_multiple_values SKIPPED [ 75%] -tests/cache/test_redis_backend.py::RedisTest::test_region_set_zero_multiple_values SKIPPED [ 75%] -tests/cache/test_redis_backend.py::RedisTest::test_region_set_zero_multiple_values_w_decorator SKIPPED [ 75%] -tests/cache/test_redis_backend.py::RedisTest::test_threaded_dogpile SKIPPED [ 75%] -tests/cache/test_redis_backend.py::RedisTest::test_threaded_get_multi SKIPPED [ 76%] -tests/cache/test_redis_backend.py::RedisDistributedMutexTest::test_mutex SKIPPED [ 76%] -tests/cache/test_redis_backend.py::RedisDistributedMutexTest::test_mutex_reentrant_across_keys SKIPPED [ 76%] -tests/cache/test_redis_backend.py::RedisDistributedMutexTest::test_mutex_threaded SKIPPED [ 76%] -tests/cache/test_redis_backend.py::RedisDistributedMutexTest::test_reentrant_dogpile SKIPPED [ 76%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_basics SKIPPED [ 77%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_connection_pool SKIPPED [ 77%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_defaults SKIPPED [ 77%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_password SKIPPED [ 77%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_socket_timeout SKIPPED [ 77%] -tests/cache/test_redis_backend.py::RedisConnectionTest::test_connect_with_url SKIPPED [ 78%] -tests/cache/test_region.py::RegionTest::test_creator PASSED [ 78%] -tests/cache/test_region.py::RegionTest::test_datetime_expiration_time PASSED [ 78%] -tests/cache/test_region.py::RegionTest::test_dupe_config PASSED [ 78%] -tests/cache/test_region.py::RegionTest::test_expire PASSED [ 78%] -tests/cache/test_region.py::RegionTest::test_expire_multi PASSED [ 79%] -tests/cache/test_region.py::RegionTest::test_expire_on_get PASSED [ 79%] -tests/cache/test_region.py::RegionTest::test_expire_override PASSED [ 79%] -tests/cache/test_region.py::RegionTest::test_hard_invalidate_get PASSED [ 79%] -tests/cache/test_region.py::RegionTest::test_hard_invalidate_get_or_create PASSED [ 79%] -tests/cache/test_region.py::RegionTest::test_ignore_expire_on_get PASSED [ 80%] -tests/cache/test_region.py::RegionTest::test_instance_from_config_string PASSED [ 80%] -tests/cache/test_region.py::RegionTest::test_instance_from_dict PASSED [ 80%] -tests/cache/test_region.py::RegionTest::test_key_mangler_argument PASSED [ 80%] -tests/cache/test_region.py::RegionTest::test_key_mangler_impl PASSED [ 80%] -tests/cache/test_region.py::RegionTest::test_multi_creator PASSED [ 81%] -tests/cache/test_region.py::RegionTest::test_no_config PASSED [ 81%] -tests/cache/test_region.py::RegionTest::test_override_expire_on_get PASSED [ 81%] -tests/cache/test_region.py::RegionTest::test_reject_invalid_expiration_time PASSED [ 81%] -tests/cache/test_region.py::RegionTest::test_remove PASSED [ 81%] -tests/cache/test_region.py::RegionTest::test_replace_backend_config PASSED [ 82%] -tests/cache/test_region.py::RegionTest::test_replace_backend_config_with_custom_key_mangler PASSED [ 82%] -tests/cache/test_region.py::RegionTest::test_set_get_nothing PASSED [ 82%] -tests/cache/test_region.py::RegionTest::test_set_get_value PASSED [ 82%] -tests/cache/test_region.py::RegionTest::test_set_name PASSED [ 82%] -tests/cache/test_region.py::RegionTest::test_should_cache_fn PASSED [ 83%] -tests/cache/test_region.py::RegionTest::test_should_cache_fn_multi PASSED [ 83%] -tests/cache/test_region.py::RegionTest::test_should_delete_multiple_values PASSED [ 83%] -tests/cache/test_region.py::RegionTest::test_should_get_multiple_values PASSED [ 83%] -tests/cache/test_region.py::RegionTest::test_should_set_multiple_values PASSED [ 83%] -tests/cache/test_region.py::RegionTest::test_soft_invalidate_get PASSED [ 84%] -tests/cache/test_region.py::RegionTest::test_soft_invalidate_get_or_create PASSED [ 84%] -tests/cache/test_region.py::RegionTest::test_soft_invalidate_get_or_create_multi PASSED [ 84%] -tests/cache/test_region.py::RegionTest::test_soft_invalidate_requires_expire_time_get PASSED [ 84%] -tests/cache/test_region.py::RegionTest::test_soft_invalidate_requires_expire_time_get_multi PASSED [ 84%] -tests/cache/test_region.py::ProxyRegionTest::test_creator PASSED [ 85%] -tests/cache/test_region.py::ProxyRegionTest::test_datetime_expiration_time PASSED [ 85%] -tests/cache/test_region.py::ProxyRegionTest::test_dupe_config PASSED [ 85%] -tests/cache/test_region.py::ProxyRegionTest::test_expire PASSED [ 85%] -tests/cache/test_region.py::ProxyRegionTest::test_expire_multi PASSED [ 85%] -tests/cache/test_region.py::ProxyRegionTest::test_expire_on_get PASSED [ 86%] -tests/cache/test_region.py::ProxyRegionTest::test_expire_override PASSED [ 86%] -tests/cache/test_region.py::ProxyRegionTest::test_hard_invalidate_get PASSED [ 86%] -tests/cache/test_region.py::ProxyRegionTest::test_hard_invalidate_get_or_create PASSED [ 86%] -tests/cache/test_region.py::ProxyRegionTest::test_ignore_expire_on_get PASSED [ 86%] -tests/cache/test_region.py::ProxyRegionTest::test_instance_from_config_string PASSED [ 87%] -tests/cache/test_region.py::ProxyRegionTest::test_instance_from_dict PASSED [ 87%] -tests/cache/test_region.py::ProxyRegionTest::test_key_mangler_argument PASSED [ 87%] -tests/cache/test_region.py::ProxyRegionTest::test_key_mangler_impl PASSED [ 87%] -tests/cache/test_region.py::ProxyRegionTest::test_multi_creator PASSED [ 87%] -tests/cache/test_region.py::ProxyRegionTest::test_no_config PASSED [ 88%] -tests/cache/test_region.py::ProxyRegionTest::test_override_expire_on_get PASSED [ 88%] -tests/cache/test_region.py::ProxyRegionTest::test_reject_invalid_expiration_time PASSED [ 88%] -tests/cache/test_region.py::ProxyRegionTest::test_remove PASSED [ 88%] -tests/cache/test_region.py::ProxyRegionTest::test_replace_backend_config PASSED [ 88%] -tests/cache/test_region.py::ProxyRegionTest::test_replace_backend_config_with_custom_key_mangler PASSED [ 89%] -tests/cache/test_region.py::ProxyRegionTest::test_set_get_nothing PASSED [ 89%] -tests/cache/test_region.py::ProxyRegionTest::test_set_get_value PASSED [ 89%] -tests/cache/test_region.py::ProxyRegionTest::test_set_name PASSED [ 89%] -tests/cache/test_region.py::ProxyRegionTest::test_should_cache_fn PASSED [ 89%] -tests/cache/test_region.py::ProxyRegionTest::test_should_cache_fn_multi PASSED [ 90%] -tests/cache/test_region.py::ProxyRegionTest::test_should_delete_multiple_values PASSED [ 90%] -tests/cache/test_region.py::ProxyRegionTest::test_should_get_multiple_values PASSED [ 90%] -tests/cache/test_region.py::ProxyRegionTest::test_should_set_multiple_values PASSED [ 90%] -tests/cache/test_region.py::ProxyRegionTest::test_soft_invalidate_get PASSED [ 90%] -tests/cache/test_region.py::ProxyRegionTest::test_soft_invalidate_get_or_create PASSED [ 91%] -tests/cache/test_region.py::ProxyRegionTest::test_soft_invalidate_get_or_create_multi PASSED [ 91%] -tests/cache/test_region.py::ProxyRegionTest::test_soft_invalidate_requires_expire_time_get PASSED [ 91%] -tests/cache/test_region.py::ProxyRegionTest::test_soft_invalidate_requires_expire_time_get_multi PASSED [ 91%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_creator PASSED [ 91%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_datetime_expiration_time PASSED [ 92%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_dupe_config PASSED [ 92%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_expire PASSED [ 92%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_expire_multi PASSED [ 92%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_expire_on_get PASSED [ 92%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_expire_override PASSED [ 93%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_hard_invalidate_get PASSED [ 93%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_hard_invalidate_get_or_create PASSED [ 93%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_ignore_expire_on_get PASSED [ 93%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_instance_from_config_string PASSED [ 93%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_instance_from_dict PASSED [ 94%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_key_mangler_argument PASSED [ 94%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_key_mangler_impl PASSED [ 94%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_multi_creator PASSED [ 94%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_no_config PASSED [ 94%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_override_expire_on_get PASSED [ 95%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_reject_invalid_expiration_time PASSED [ 95%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_remove PASSED [ 95%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_replace_backend_config PASSED [ 95%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_replace_backend_config_with_custom_key_mangler PASSED [ 95%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_set_get_nothing PASSED [ 96%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_set_get_value PASSED [ 96%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_set_name PASSED [ 96%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_should_cache_fn PASSED [ 96%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_should_cache_fn_multi PASSED [ 96%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_should_delete_multiple_values PASSED [ 97%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_should_get_multiple_values PASSED [ 97%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_should_set_multiple_values PASSED [ 97%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_soft_invalidate_get PASSED [ 97%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_soft_invalidate_get_or_create PASSED [ 97%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_soft_invalidate_get_or_create_multi PASSED [ 98%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_soft_invalidate_requires_expire_time_get PASSED [ 98%] -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_soft_invalidate_requires_expire_time_get_multi PASSED [ 98%] -tests/cache/test_region.py::ProxyBackendTest::test_argument_proxies PASSED [ 98%] -tests/cache/test_region.py::ProxyBackendTest::test_cachedvalue_passed PASSED [ 98%] -tests/cache/test_region.py::ProxyBackendTest::test_counter_proxies PASSED [ 99%] -tests/cache/test_region.py::ProxyBackendTest::test_instance_proxies PASSED [ 99%] -tests/cache/plugins/test_mako_cache.py::TestMakoPlugin::test_basic PASSED [ 99%] -tests/cache/plugins/test_mako_cache.py::TestMakoPlugin::test_timeout PASSED [ 99%] -tests/util/test_nameregistry.py::NameRegistryTest::test_name_registry PASSED [100%] - -=============================== warnings summary =============================== -/usr/lib/python3/dist-packages/_pytest/config/findpaths.py:44 - /usr/lib/python3/dist-packages/_pytest/config/findpaths.py:44: RemovedInPytest4Warning: [pytest] section in setup.cfg files is deprecated, use [tool:pytest] instead. - config=config, - -dogpile/util/nameregistry.py:62 - /build/python-dogpile.cache-0.6.2/dogpile/util/nameregistry.py:62: DeprecationWarning: invalid escape sequence \* - """ - -dogpile/cache/region.py:327 - /build/python-dogpile.cache-0.6.2/dogpile/cache/region.py:327: DeprecationWarning: invalid escape sequence \* - """ - -tests/cache/test_dbm_backend.py::DBMBackendTest::test_decorated_fn_functionality - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_dbm_backend.py::DBMBackendConditionTest::test_decorated_fn_functionality - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_dbm_backend.py::DBMBackendNoLockTest::test_decorated_fn_functionality - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_decorator - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_decorator_custom_expire - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_decorator_expire_callable - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_decorator_expire_callable_zero - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_decorator_namespace - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_expire - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_get - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_get_multi - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_refresh - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_refresh_multi - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_set - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::DecoratorTest::test_explicit_set_multi - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_default_keygen_kwargs_raises_value_error - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_key_isnt_unicode_bydefault - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_keygen_fn - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_keygen_fn_namespace - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn_all_defaults - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kegen_keygen_fn_with_defaults_and_positional - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_keygen_fn_namespace - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_kwarg_kwgen_key_isnt_unicode_bydefault - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_multi_keygen_fn - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_by_default - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_by_default_kwarg_generator - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_kwarg_generator - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:91: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - argspec = inspect.getargspec(fn) - -tests/cache/test_decorator.py::KeyGenerationTest::test_unicode_key_multi - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_cache_arg - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict_keys_missing - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_asdict_keys_missing_existing_cache_fn - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_multi_namespace - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:53: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_original_fn_set - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_decorator.py::CacheDecoratorTest::test_reentrant_call - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_memory_backend.py::MemoryBackendTest::test_decorated_fn_functionality - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_memory_backend.py::MemoryPickleBackendTest::test_decorated_fn_functionality - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_null_backend.py::NullBackendTest::test_decorator - /build/python-dogpile.cache-0.6.2/dogpile/cache/util.py:31: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec() - args = inspect.getargspec(fn) - -tests/cache/test_region.py::RegionTest::test_instance_from_config_string - /build/python-dogpile.cache-0.6.2/tests/cache/test_region.py:54: DeprecationWarning: This method will be removed in future versions. Use 'parser.read_file()' instead. - config.readfp(io.StringIO(my_conf)) - -tests/cache/test_region.py::ProxyRegionTest::test_instance_from_config_string - /build/python-dogpile.cache-0.6.2/tests/cache/test_region.py:54: DeprecationWarning: This method will be removed in future versions. Use 'parser.read_file()' instead. - config.readfp(io.StringIO(my_conf)) - -tests/cache/test_region.py::CustomInvalidationStrategyTest::test_instance_from_config_string - /build/python-dogpile.cache-0.6.2/tests/cache/test_region.py:54: DeprecationWarning: This method will be removed in future versions. Use 'parser.read_file()' instead. - config.readfp(io.StringIO(my_conf)) - --- Docs: https://docs.pytest.org/en/latest/warnings.html -============ 290 passed, 206 skipped, 48 warnings in 223.32 seconds ============ -make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - fakeroot debian/rules binary -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -dh binary --buildsystem=python_distutils --with python2,python3,sphinxdoc - dh_testroot -O--buildsystem=python_distutils - dh_prep -O--buildsystem=python_distutils - debian/rules override_dh_auto_install -make[1]: Entering directory '/build/python-dogpile.cache-0.6.2' -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -set -e && for pyvers in 2.7; do \ - python$pyvers setup.py install --install-layout=deb \ - --root /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache; \ -done -running install -running build -running build_py -running install_lib -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7 -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/file.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/memcached.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/memory.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/null.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -copying build/lib.linux-x86_64-2.7/dogpile/cache/backends/redis.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/plugins -copying build/lib.linux-x86_64-2.7/dogpile/cache/plugins/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/plugins -copying build/lib.linux-x86_64-2.7/dogpile/cache/plugins/mako_cache.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/plugins -copying build/lib.linux-x86_64-2.7/dogpile/cache/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -copying build/lib.linux-x86_64-2.7/dogpile/cache/api.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -copying build/lib.linux-x86_64-2.7/dogpile/cache/exception.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -copying build/lib.linux-x86_64-2.7/dogpile/cache/proxy.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -copying build/lib.linux-x86_64-2.7/dogpile/cache/region.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -copying build/lib.linux-x86_64-2.7/dogpile/cache/util.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache -creating /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/util/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/util/compat.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/util/langhelpers.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/util/nameregistry.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/util/readwrite_lock.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util -copying build/lib.linux-x86_64-2.7/dogpile/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile -copying build/lib.linux-x86_64-2.7/dogpile/core.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile -copying build/lib.linux-x86_64-2.7/dogpile/lock.py -> /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/__init__.py to __init__.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/file.py to file.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/memcached.py to memcached.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/memory.py to memory.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/null.py to null.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/backends/redis.py to redis.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/plugins/__init__.py to __init__.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/plugins/mako_cache.py to mako_cache.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/__init__.py to __init__.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/api.py to api.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/exception.py to exception.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/proxy.py to proxy.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/region.py to region.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/cache/util.py to util.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util/__init__.py to __init__.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util/compat.py to compat.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util/langhelpers.py to langhelpers.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util/nameregistry.py to nameregistry.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/util/readwrite_lock.py to readwrite_lock.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/__init__.py to __init__.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/core.py to core.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile/lock.py to lock.pyc -running install_egg_info -running egg_info -creating dogpile.cache.egg-info -writing dogpile.cache.egg-info/PKG-INFO -writing top-level names to dogpile.cache.egg-info/top_level.txt -writing dependency_links to dogpile.cache.egg-info/dependency_links.txt -writing entry points to dogpile.cache.egg-info/entry_points.txt -writing manifest file 'dogpile.cache.egg-info/SOURCES.txt' -reading manifest file 'dogpile.cache.egg-info/SOURCES.txt' -reading manifest template 'MANIFEST.in' -warning: no files found matching '*.html' under directory 'docs' -warning: no files found matching '*.css' under directory 'docs' -warning: no files found matching '*.js' under directory 'docs' -warning: no files found matching '*.jpg' under directory 'docs' -warning: no files found matching '*.png' under directory 'docs' -warning: no files found matching '*.sty' under directory 'docs' -warning: no files found matching '*.dat' under directory 'tests' -warning: no files found matching 'CHANGES*' -no previously-included directories found matching 'docs/build/output' -writing manifest file 'dogpile.cache.egg-info/SOURCES.txt' -Copying dogpile.cache.egg-info to /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache/usr/lib/python2.7/dist-packages/dogpile.cache-0.6.2.egg-info -Skipping SOURCES.txt -running install_scripts -set -e && for pyvers in 3.7; do \ - python$pyvers setup.py install --install-layout=deb \ - --root /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache; \ -done -running install -running build -running build_py -creating build/lib -creating build/lib/dogpile -copying dogpile/lock.py -> build/lib/dogpile -copying dogpile/core.py -> build/lib/dogpile -copying dogpile/__init__.py -> build/lib/dogpile -creating build/lib/dogpile/util -copying dogpile/util/readwrite_lock.py -> build/lib/dogpile/util -copying dogpile/util/nameregistry.py -> build/lib/dogpile/util -copying dogpile/util/langhelpers.py -> build/lib/dogpile/util -copying dogpile/util/compat.py -> build/lib/dogpile/util -copying dogpile/util/__init__.py -> build/lib/dogpile/util -creating build/lib/dogpile/cache -copying dogpile/cache/util.py -> build/lib/dogpile/cache -copying dogpile/cache/region.py -> build/lib/dogpile/cache -copying dogpile/cache/proxy.py -> build/lib/dogpile/cache -copying dogpile/cache/exception.py -> build/lib/dogpile/cache -copying dogpile/cache/api.py -> build/lib/dogpile/cache -copying dogpile/cache/__init__.py -> build/lib/dogpile/cache -creating build/lib/dogpile/cache/plugins -copying dogpile/cache/plugins/mako_cache.py -> build/lib/dogpile/cache/plugins -copying dogpile/cache/plugins/__init__.py -> build/lib/dogpile/cache/plugins -creating build/lib/dogpile/cache/backends -copying dogpile/cache/backends/redis.py -> build/lib/dogpile/cache/backends -copying dogpile/cache/backends/null.py -> build/lib/dogpile/cache/backends -copying dogpile/cache/backends/memory.py -> build/lib/dogpile/cache/backends -copying dogpile/cache/backends/memcached.py -> build/lib/dogpile/cache/backends -copying dogpile/cache/backends/file.py -> build/lib/dogpile/cache/backends -copying dogpile/cache/backends/__init__.py -> build/lib/dogpile/cache/backends -running install_lib -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3 -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/file.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/memcached.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/memory.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/null.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -copying build/lib/dogpile/cache/backends/redis.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/plugins -copying build/lib/dogpile/cache/plugins/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/plugins -copying build/lib/dogpile/cache/plugins/mako_cache.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/plugins -copying build/lib/dogpile/cache/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -copying build/lib/dogpile/cache/api.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -copying build/lib/dogpile/cache/exception.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -copying build/lib/dogpile/cache/proxy.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -copying build/lib/dogpile/cache/region.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -copying build/lib/dogpile/cache/util.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache -creating /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/util/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/util/compat.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/util/langhelpers.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/util/nameregistry.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/util/readwrite_lock.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util -copying build/lib/dogpile/__init__.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile -copying build/lib/dogpile/core.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile -copying build/lib/dogpile/lock.py -> /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/__init__.py to __init__.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/file.py to file.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/memcached.py to memcached.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/memory.py to memory.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/null.py to null.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/backends/redis.py to redis.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/plugins/__init__.py to __init__.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/plugins/mako_cache.py to mako_cache.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/__init__.py to __init__.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/api.py to api.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/exception.py to exception.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/proxy.py to proxy.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/region.py to region.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/cache/util.py to util.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util/__init__.py to __init__.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util/compat.py to compat.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util/langhelpers.py to langhelpers.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util/nameregistry.py to nameregistry.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/util/readwrite_lock.py to readwrite_lock.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/__init__.py to __init__.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/core.py to core.cpython-37.pyc -byte-compiling /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile/lock.py to lock.cpython-37.pyc -running install_egg_info -running egg_info -writing dogpile.cache.egg-info/PKG-INFO -writing dependency_links to dogpile.cache.egg-info/dependency_links.txt -writing entry points to dogpile.cache.egg-info/entry_points.txt -writing top-level names to dogpile.cache.egg-info/top_level.txt -reading manifest file 'dogpile.cache.egg-info/SOURCES.txt' -reading manifest template 'MANIFEST.in' -warning: no files found matching '*.html' under directory 'docs' -warning: no files found matching '*.css' under directory 'docs' -warning: no files found matching '*.js' under directory 'docs' -warning: no files found matching '*.jpg' under directory 'docs' -warning: no files found matching '*.png' under directory 'docs' -warning: no files found matching '*.sty' under directory 'docs' -warning: no files found matching '*.dat' under directory 'tests' -warning: no files found matching 'CHANGES*' -no previously-included directories found matching 'docs/build/output' -writing manifest file 'dogpile.cache.egg-info/SOURCES.txt' -Copying dogpile.cache.egg-info to /build/python-dogpile.cache-0.6.2/debian/python3-dogpile.cache/usr/lib/python3/dist-packages/dogpile.cache-0.6.2.egg-info -Skipping SOURCES.txt -running install_scripts +======= 1 failed, 287 passed, 208 skipped, 1 warnings in 230.64 seconds ======== +make[1]: *** [debian/rules:24: override_dh_auto_test] Error 1 make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - dh_installdocs -O--buildsystem=python_distutils - debian/rules override_dh_sphinxdoc -make[1]: Entering directory '/build/python-dogpile.cache-0.6.2' -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -PYTHONPATH=. sphinx-build -b html docs/build /build/python-dogpile.cache-0.6.2/debian/python-dogpile.cache-doc/usr/share/doc/python-dogpile.cache-doc/html -Running Sphinx v1.8.4 -making output directory... -building [mo]: targets for 0 po files that are out of date -building [html]: targets for 8 source files that are out of date -updating environment: 8 added, 0 changed, 0 removed -reading sources... [ 12%] api -reading sources... [ 25%] changelog -reading sources... [ 37%] core_usage -reading sources... [ 50%] front -reading sources... [ 62%] index -reading sources... [ 75%] recipes -reading sources... [ 87%] unreleased/119 -reading sources... [100%] usage - -/build/python-dogpile.cache-0.6.2/dogpile/cache/backends/redis.py:docstring of dogpile.cache.backends.redis.RedisBackend.set_multi:19: WARNING: Duplicate explicit target name: "redis". -looking for now-outdated files... none found -pickling environment... done -checking consistency... /build/python-dogpile.cache-0.6.2/docs/build/unreleased/119.rst: WARNING: document isn't included in any toctree -done -preparing documents... done -writing output... [ 12%] api -writing output... [ 25%] changelog -writing output... [ 37%] core_usage -writing output... [ 50%] front -writing output... [ 62%] index -writing output... [ 75%] recipes -writing output... [ 87%] unreleased/119 -writing output... [100%] usage - -generating indices... genindex py-modindex -writing additional pages... search -copying static files... WARNING: html_static_path entry u'/build/python-dogpile.cache-0.6.2/docs/build/_static' does not exist -done -copying extra files... done -dumping search index in English (code: en) ... done -dumping object inventory... done -build succeeded, 3 warnings. - -The HTML pages are in debian/python-dogpile.cache-doc/usr/share/doc/python-dogpile.cache-doc/html. -The name of the builder is: htmlCopying sphinx_paramlinks stylesheet... done -The name of the builder is: htmlCopying sphinx_paramlinks stylesheet... done -dh_sphinxdoc -O--buildsystem=python_distutils -make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - debian/rules override_dh_installchangelogs -make[1]: Entering directory '/build/python-dogpile.cache-0.6.2' -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -if [ -e /build/python-dogpile.cache-0.6.2/debian/CHANGELOG ] ; then \ - dh_installchangelogs /build/python-dogpile.cache-0.6.2/debian/CHANGELOG ; \ -else \ - dh_installchangelogs ; \ -fi -make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - dh_python2 -O--buildsystem=python_distutils - dh_python3 -O--buildsystem=python_distutils - debian/rules override_dh_installinit -make[1]: Entering directory '/build/python-dogpile.cache-0.6.2' -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -# Create the init scripts and systemd unit files from the template -set -e ; set -x ; for i in `ls -1 debian/*.init.in` ; do \ - MYINIT=`echo $i | sed s/.init.in//` ; \ - cp $i $MYINIT.init ; \ - cat /usr/share/openstack-pkg-tools/init-script-template >>$MYINIT.init ; \ - pkgos-gen-systemd-unit $i ; \ -done -+ ls -1 debian/*.init.in -ls: cannot access 'debian/*.init.in': No such file or directory -# If there's a service.in file, use that one instead of the generated one -set -e ; set -x ; for i in `ls -1 debian/*.service.in`; do \ - MYPKG=`echo $i | sed s/.service.in//` ; \ - cp $MYPKG.service.in $MYPKG.service ; \ -done -+ ls -1 debian/*.service.in -ls: cannot access 'debian/*.service.in': No such file or directory -# Generate the systemd unit if there's no already existing .service.in -set -e ; set -x ; for i in `ls debian/*.init.in` ; do \ - MYINIT=`echo $i | sed s/.init.in/.service.in/` ; \ - if ! [ -e $MYINIT ] ; then \ - pkgos-gen-systemd-unit $i ; \ - fi \ -done -+ ls debian/*.init.in -ls: cannot access 'debian/*.init.in': No such file or directory -dh_installinit --error-handler=true -make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - dh_perl -O--buildsystem=python_distutils - dh_link -O--buildsystem=python_distutils - dh_strip_nondeterminism -O--buildsystem=python_distutils - dh_compress -O--buildsystem=python_distutils - dh_fixperms -O--buildsystem=python_distutils - dh_missing -O--buildsystem=python_distutils - dh_installdeb -O--buildsystem=python_distutils - debian/rules override_dh_gencontrol -make[1]: Entering directory '/build/python-dogpile.cache-0.6.2' -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -pyversions: missing X(S)-Python-Version in control file, fall back to debian/pyversions -pyversions: missing debian/pyversions file, fall back to supported versions -py3versions: no X-Python3-Version in control file, using supported versions -if dpkg-vendor --derives-from ubuntu ; then \ - dh_gencontrol -- -T/build/python-dogpile.cache-0.6.2/debian/ubuntu_control_vars ; \ -else \ - dh_gencontrol -- -T/build/python-dogpile.cache-0.6.2/debian/debian_control_vars ; \ -fi -dpkg-gencontrol: warning: Recommends field of package python-dogpile.cache: substitution variable ${python:Recommends} used, but is not defined -dpkg-gencontrol: warning: Recommends field of package python3-dogpile.cache: substitution variable ${python:Recommends} used, but is not defined -dpkg-gencontrol: warning: package python-dogpile.cache: substitution variable ${python:Provides} unused, but is defined -dpkg-gencontrol: warning: package python-dogpile.cache: substitution variable ${python:Versions} unused, but is defined -dpkg-gencontrol: warning: package python-dogpile.cache-doc: substitution variable ${sphinxdoc:Built-Using} unused, but is defined -make[1]: Leaving directory '/build/python-dogpile.cache-0.6.2' - dh_md5sums -O--buildsystem=python_distutils - dh_builddeb -O--buildsystem=python_distutils -dpkg-deb: building package 'python-dogpile.cache-doc' in '../python-dogpile.cache-doc_0.6.2-6_all.deb'. -dpkg-deb: building package 'python-dogpile.cache' in '../python-dogpile.cache_0.6.2-6_all.deb'. -dpkg-deb: building package 'python3-dogpile.core' in '../python3-dogpile.core_0.6.2-6_all.deb'. -dpkg-deb: building package 'python-dogpile.core' in '../python-dogpile.core_0.6.2-6_all.deb'. -dpkg-deb: building package 'python3-dogpile.cache' in '../python3-dogpile.cache_0.6.2-6_all.deb'. - dpkg-genbuildinfo --build=binary - dpkg-genchanges --build=binary >../python-dogpile.cache_0.6.2-6_amd64.changes -dpkg-genchanges: info: binary-only upload (no source code included) - dpkg-source --after-build . -dpkg-source: info: using options from python-dogpile.cache-0.6.2/debian/source/options: --extend-diff-ignore=^[.]gitreview$ -dpkg-buildpackage: info: binary-only upload (no source included) -dpkg-genchanges: info: not including original source code in upload +make: *** [debian/rules:10: build] Error 2 +dpkg-buildpackage: error: debian/rules build subprocess returned exit status 2 I: copying local configuration +E: Failed autobuilding of package +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/C01_cleanup starting +debug output: disk usage on i-capture-the-hostname at Wed Sep 27 06:03:41 UTC 2023 +Filesystem Size Used Avail Use% Mounted on +tmpfs 24G 0 24G 0% /dev/shm + +I: user script /srv/workspace/pbuilder/1551875/tmp/hooks/C01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/2638997 and its subdirectories -I: Current time: Wed Aug 24 11:33:11 -12 2022 -I: pbuilder-time-stamp: 1661383991 +I: removing directory /srv/workspace/pbuilder/1551875 and its subdirectories