Diff of the two buildlogs: -- --- b1/build.log 2024-04-26 17:05:21.646304854 +0000 +++ b2/build.log 2024-04-26 17:29:07.473476211 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu May 29 11:23:38 -12 2025 -I: pbuilder-time-stamp: 1748561018 +I: Current time: Sat Apr 27 07:05:25 +14 2024 +I: pbuilder-time-stamp: 1714151125 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -27,52 +27,84 @@ dpkg-source: info: applying 01-remove-jaeger.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/1022324/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/D01_modify_environment starting +debug: Running on ionos1-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 +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Apr 26 17:05 /bin/sh -> /bin/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/3342912/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - 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=42 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + 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=20 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='deda69d412954e778c3116458ceed383' - 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='1022324' - PS1='# ' - PS2='> ' + INVOCATION_ID=ce9243c657e04d80abab6fe83f3ca56d + 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=3342912 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/r-b-build.UaaaQc3v/pbuilderrc_4ABA --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.UaaaQc3v/b1 --logfile b1/build.log mtail_3.0.0~rc54+git0ff5-1.dsc' - SUDO_GID='111' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://213.165.73.152: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/r-b-build.UaaaQc3v/pbuilderrc_n3Wp --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.UaaaQc3v/b2 --logfile b2/build.log mtail_3.0.0~rc54+git0ff5-1.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://46.16.76.132:3128 I: uname -a - Linux ionos15-amd64 6.6.13+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.6.13-1~bpo12+1 (2024-02-15) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.85-1 (2024-04-11) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 27 17:46 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1022324/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 22 13:10 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -85,7 +117,7 @@ Depends: debhelper-compat (= 13), dh-golang, golang-any, golang-github-fsnotify-fsnotify-dev, golang-github-golang-groupcache-dev, golang-github-google-go-cmp-dev, golang-github-pkg-errors-dev, golang-github-prometheus-client-golang-dev (>= 1.11~), golang-glog-dev, golang-go | gccgo, golang-go.opencensus-dev, golang-golang-x-tools, tzdata dpkg-deb: building package 'pbuilder-satisfydepends-dummy' in '/tmp/satisfydepends-aptitude/pbuilder-satisfydepends-dummy.deb'. Selecting previously unselected package pbuilder-satisfydepends-dummy. -(Reading database ... 19722 files and directories currently installed.) +(Reading database ... 19898 files and directories currently installed.) Preparing to unpack .../pbuilder-satisfydepends-dummy.deb ... Unpacking pbuilder-satisfydepends-dummy (0.invalid.0) ... dpkg: pbuilder-satisfydepends-dummy: dependency problems, but configuring anyway as you requested: @@ -216,10 +248,10 @@ Get: 81 http://deb.debian.org/debian trixie/main amd64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [326 kB] Get: 82 http://deb.debian.org/debian trixie/main amd64 libjs-jquery-ui all 1.13.2+dfsg-1 [250 kB] Get: 83 http://deb.debian.org/debian trixie/main amd64 golang-golang-x-tools amd64 1:0.19.0+ds-1 [38.6 MB] -Fetched 129 MB in 7s (19.4 MB/s) +Fetched 129 MB in 7s (18.6 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package tzdata. -(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 ... 19722 files and directories currently installed.) +(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 ... 19898 files and directories currently installed.) Preparing to unpack .../00-tzdata_2024a-3_all.deb ... Unpacking tzdata (2024a-3) ... Selecting previously unselected package sensible-utils. @@ -493,8 +525,8 @@ Setting up tzdata (2024a-3) ... Current default time zone: 'Etc/UTC' -Local time is now: Thu May 29 23:25:25 UTC 2025. -Universal Time is now: Thu May 29 23:25:25 UTC 2025. +Local time is now: Fri Apr 26 17:10:11 UTC 2024. +Universal Time is now: Fri Apr 26 17:10:11 UTC 2024. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up golang-github-cespare-xxhash-dev (2.1.1-2) ... @@ -569,7 +601,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/mtail-3.0.0~rc54+git0ff5/ && 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 > ../mtail_3.0.0~rc54+git0ff5-1_source.changes +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/mtail-3.0.0~rc54+git0ff5/ && 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 > ../mtail_3.0.0~rc54+git0ff5-1_source.changes dpkg-buildpackage: info: source package mtail dpkg-buildpackage: info: source version 3.0.0~rc54+git0ff5-1 dpkg-buildpackage: info: source distribution unstable @@ -722,270 +758,270 @@ src/github.com/google/mtail/internal/waker/waker.go src/github.com/google/mtail/internal/waker/testwaker_test.go src/github.com/google/mtail/internal/waker/timedwaker_test.go - cd build && go install -trimpath -v -p 42 -ldflags " -X main.Version=3.0.0~rc54+git0ff5 -X main.Revision=3.0.0~rc54+git0ff5-1 -X main.Branch=Debian/unstable" github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker -internal/goos -internal/goexperiment + cd build && go install -trimpath -v -p 20 -ldflags " -X main.Version=3.0.0~rc54+git0ff5 -X main.Revision=3.0.0~rc54+git0ff5-1 -X main.Branch=Debian/unstable" github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker +encoding +cmp +unicode/utf16 +internal/goarch internal/godebugs +container/list +unicode +internal/coverage/rtcov +log/internal +internal/unsafeheader +internal/race +internal/goos +runtime/internal/atomic +crypto/internal/alias unicode/utf8 -math/bits -vendor/golang.org/x/crypto/internal/alias -vendor/golang.org/x/crypto/cryptobyte/asn1 +crypto/subtle +internal/goexperiment internal/cpu internal/itoa -go.opencensus.io -runtime/internal/syscall -internal/goarch -runtime/internal/atomic -github.com/google/go-cmp/cmp/internal/flags -google.golang.org/protobuf/internal/set -google.golang.org/protobuf/internal/flags -log/internal -unicode/utf16 -internal/unsafeheader -encoding -internal/coverage/rtcov crypto/internal/boring/sig -container/list -crypto/internal/alias +vendor/golang.org/x/crypto/cryptobyte/asn1 sync/atomic -go.opencensus.io/internal/tagencoding -go.opencensus.io/trace/internal -internal/race -crypto/subtle -cmp internal/nettrace -unicode -runtime/internal/math +runtime/internal/syscall +vendor/golang.org/x/crypto/internal/alias +math/bits +google.golang.org/protobuf/internal/flags +github.com/google/go-cmp/cmp/internal/flags +go.opencensus.io +go.opencensus.io/internal/tagencoding +internal/abi internal/chacha8rand +runtime/internal/math runtime/internal/sys -internal/abi +go.opencensus.io/trace/internal slices +google.golang.org/protobuf/internal/set +github.com/golang/groupcache/lru internal/bytealg math -github.com/golang/groupcache/lru runtime internal/reflectlite -runtime/metrics sync +runtime/metrics internal/testlog -internal/singleflight internal/bisect +internal/singleflight google.golang.org/protobuf/internal/pragma runtime/cgo -internal/godebug errors -sort -internal/oserror -internal/safefilepath +strconv io -path +sort +internal/godebug math/rand -strconv +path +internal/oserror crypto/internal/nistec/fiat -internal/intern +internal/safefilepath vendor/golang.org/x/net/dns/dnsmessage +internal/intern syscall -github.com/beorn7/perks/quantile +strings +bytes hash -crypto/internal/randutil text/tabwriter -bytes -strings -hash/fnv +crypto/internal/randutil +github.com/beorn7/perks/quantile hash/crc32 -github.com/golang/glog/internal/stackdump -vendor/golang.org/x/text/transform +hash/fnv crypto -net/netip crypto/rc4 +net/netip reflect -net/http/internal/ascii -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg +github.com/golang/glog/internal/stackdump +vendor/golang.org/x/text/transform bufio +net/http/internal/ascii regexp/syntax +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg html -internal/syscall/execenv +regexp internal/syscall/unix +internal/syscall/execenv time -regexp -github.com/google/go-cmp/cmp/internal/diff +internal/poll context +github.com/google/go-cmp/cmp/internal/diff io/fs -internal/poll -github.com/google/go-cmp/cmp/internal/function -internal/fmtsort encoding/binary +internal/fmtsort +github.com/google/go-cmp/cmp/internal/function embed github.com/google/mtail/internal/logline google.golang.org/protobuf/internal/editiondefaults -os encoding/base64 crypto/internal/edwards25519/field crypto/cipher crypto/md5 -github.com/cespare/xxhash vendor/golang.org/x/crypto/internal/poly1305 +github.com/cespare/xxhash crypto/internal/nistec golang.org/x/sys/unix encoding/pem +os crypto/internal/boring vendor/golang.org/x/crypto/chacha20 crypto/des crypto/internal/edwards25519 -io/ioutil -google.golang.org/protobuf/internal/detrand -internal/sysinfo -github.com/prometheus/procfs/internal/util -path/filepath -internal/lazyregexp -vendor/golang.org/x/sys/cpu -fmt -os/signal +crypto/sha512 crypto/hmac -crypto/sha256 crypto/sha1 +crypto/sha256 crypto/aes -crypto/sha512 -crypto/ecdh vendor/golang.org/x/crypto/hkdf +crypto/ecdh +fmt +path/filepath +vendor/golang.org/x/sys/cpu +google.golang.org/protobuf/internal/detrand +io/ioutil +github.com/prometheus/procfs/internal/util +internal/sysinfo +os/signal +internal/lazyregexp +net vendor/golang.org/x/crypto/chacha20poly1305 -log -github.com/google/mtail/internal/runtime/compiler/position flag -encoding/hex +github.com/golang/glog/internal/logsink +log +os/user +compress/flate +encoding/json github.com/pkg/errors +encoding/hex +net/url net/http/internal mime/quotedprintable -net/url -go/token -database/sql/driver -compress/flate -github.com/golang/glog/internal/logsink -go.opencensus.io/trace/tracestate -google.golang.org/protobuf/internal/errors +math/big +github.com/google/mtail/internal/runtime/compiler/position mime +vendor/golang.org/x/net/http2/hpack +google.golang.org/protobuf/internal/errors +go/token +vendor/golang.org/x/text/unicode/norm google.golang.org/protobuf/internal/version +github.com/prometheus/procfs/internal/fs +google.golang.org/protobuf/encoding/protowire runtime/debug -google.golang.org/grpc/internal/grpclog +text/template/parse +github.com/google/mtail/internal/runtime/compiler/errors github.com/google/go-cmp/cmp/internal/value -encoding/json -google.golang.org/grpc/metadata -math/big -github.com/prometheus/procfs/internal/fs runtime/trace +database/sql/driver go.opencensus.io/internal -text/template/parse go.opencensus.io/resource -google.golang.org/grpc/codes -vendor/golang.org/x/net/http2/hpack -vendor/golang.org/x/text/unicode/norm -google.golang.org/protobuf/encoding/protowire -go.opencensus.io/metric/metricdata -github.com/google/mtail/internal/runtime/compiler/errors +go.opencensus.io/trace/tracestate google.golang.org/protobuf/reflect/protoreflect -github.com/google/go-cmp/cmp -google.golang.org/grpc/grpclog +go.opencensus.io/metric/metricdata vendor/golang.org/x/text/unicode/bidi +google.golang.org/grpc/codes +google.golang.org/grpc/internal/grpclog go.opencensus.io/metric/metricproducer +github.com/google/go-cmp/cmp +google.golang.org/grpc/metadata +google.golang.org/grpc/grpclog database/sql testing compress/gzip +vendor/golang.org/x/text/secure/bidirule runtime/pprof internal/profile -vendor/golang.org/x/text/secure/bidirule -google.golang.org/protobuf/internal/descopts +vendor/golang.org/x/net/idna google.golang.org/protobuf/internal/descfmt +google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/encoding/messageset -google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order google.golang.org/protobuf/runtime/protoiface +google.golang.org/protobuf/internal/genid text/template -google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/reflect/protoregistry -vendor/golang.org/x/net/idna +google.golang.org/protobuf/internal/encoding/text github.com/google/mtail/internal/metrics/datum github.com/golang/protobuf/proto -os/user -net google.golang.org/protobuf/proto go.opencensus.io/tag -google.golang.org/protobuf/internal/encoding/defval -go.opencensus.io/stats/internal +github.com/google/go-cmp/cmp/cmpopts crypto/rand crypto/elliptic -crypto/internal/boring/bbig crypto/internal/bigmod -encoding/asn1 +crypto/internal/boring/bbig crypto/dsa +encoding/asn1 +google.golang.org/protobuf/internal/encoding/defval +go.opencensus.io/stats/internal go.opencensus.io/stats -github.com/google/go-cmp/cmp/cmpopts -go.opencensus.io/stats/view crypto/ed25519 go.opencensus.io/trace -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/filedesc -google.golang.org/protobuf/encoding/protodelim +go.opencensus.io/stats/view crypto/rsa github.com/prometheus/common/version html/template crypto/x509/pkix vendor/golang.org/x/crypto/cryptobyte +github.com/golang/glog +google.golang.org/protobuf/internal/filedesc +google.golang.org/protobuf/encoding/protodelim +google.golang.org/protobuf/encoding/prototext crypto/ecdsa +github.com/google/mtail/internal/metrics +github.com/google/mtail/internal/waker +github.com/google/mtail/internal/runtime/compiler/types +github.com/google/mtail/internal/runtime/code +github.com/google/mtail/internal/mtail/golden +github.com/google/mtail/internal/runtime/compiler/symbol +github.com/google/mtail/internal/runtime/compiler/ast +github.com/google/mtail/internal/runtime/compiler/parser google.golang.org/protobuf/internal/encoding/tag -google.golang.org/protobuf/internal/impl github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration github.com/golang/protobuf/ptypes/timestamp -github.com/golang/glog google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes +google.golang.org/protobuf/internal/impl google.golang.org/grpc/internal/status google.golang.org/grpc/status -github.com/google/mtail/internal/metrics -github.com/google/mtail/internal/runtime/compiler/types -github.com/google/mtail/internal/waker -github.com/google/mtail/internal/runtime/compiler/symbol -github.com/google/mtail/internal/mtail/golden -github.com/google/mtail/internal/runtime/code -github.com/google/mtail/internal/runtime/compiler/ast -github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/cmd/mfmt github.com/google/mtail/internal/runtime/compiler -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/runtime/protoimpl -google.golang.org/protobuf/types/known/timestamppb -github.com/prometheus/client_model/go -github.com/prometheus/common/model -github.com/prometheus/client_golang/prometheus/internal -vendor/golang.org/x/net/http/httpproxy +crypto/x509 net/textproto +vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/stats -crypto/x509 github.com/prometheus/procfs vendor/golang.org/x/net/http/httpguts mime/multipart crypto/tls +google.golang.org/protobuf/internal/filetype +google.golang.org/protobuf/runtime/protoimpl +google.golang.org/protobuf/types/known/timestamppb +github.com/prometheus/client_model/go +github.com/prometheus/common/model +github.com/prometheus/client_golang/prometheus/internal net/http/httptrace net/http -net/http/pprof -go.opencensus.io/trace/propagation expvar -go.opencensus.io/zpages/internal github.com/prometheus/common/expfmt +go.opencensus.io/trace/propagation +go.opencensus.io/zpages/internal +net/http/pprof go.opencensus.io/plugin/ocgrpc -github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil +github.com/google/mtail/internal/tailer/logstream go.opencensus.io/zpages github.com/google/mtail/internal/tailer github.com/prometheus/client_golang/prometheus -github.com/prometheus/client_golang/prometheus/collectors/version github.com/google/mtail/internal/exporter -github.com/prometheus/client_golang/prometheus/collectors github.com/google/mtail/internal/runtime/vm +github.com/prometheus/client_golang/prometheus/collectors/version +github.com/prometheus/client_golang/prometheus/collectors github.com/prometheus/client_golang/prometheus/promhttp github.com/google/mtail/internal/runtime github.com/google/mtail/internal/mtail @@ -994,7 +1030,7 @@ debian/rules override_dh_auto_test make[1]: Entering directory '/build/reproducible-path/mtail-3.0.0~rc54+git0ff5' dh_auto_test -- -timeout 100s - cd build && go test -vet=off -v -p 42 -timeout 100s github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker + cd build && go test -vet=off -v -p 20 -timeout 100s github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker ? github.com/google/mtail/cmd/mfmt [no test files] ? github.com/google/mtail/cmd/mtail [no test files] ? github.com/google/mtail/internal/logline [no test files] @@ -1037,7 +1073,7 @@ === RUN TestHandlePrometheus/2_help_with_label === RUN TestHandlePrometheus/histo === RUN TestHandlePrometheus/histo-count-eq-inf ---- PASS: TestHandlePrometheus (0.00s) +--- PASS: TestHandlePrometheus (0.01s) --- PASS: TestHandlePrometheus/empty (0.00s) --- PASS: TestHandlePrometheus/single (0.00s) --- PASS: TestHandlePrometheus/with_prog_label (0.00s) @@ -1069,7 +1105,7 @@ --- PASS: TestHandleVarz/dimensioned (0.00s) --- PASS: TestHandleVarz/text (0.00s) PASS -ok github.com/google/mtail/internal/exporter 0.010s +ok github.com/google/mtail/internal/exporter 0.104s === RUN TestKindType --- PASS: TestKindType (0.00s) === RUN TestScalarMetric @@ -1087,7 +1123,7 @@ === RUN TestAppendLabelValue --- PASS: TestAppendLabelValue (0.00s) === RUN TestMetricJSONRoundTrip ---- PASS: TestMetricJSONRoundTrip (0.03s) +--- PASS: TestMetricJSONRoundTrip (0.20s) === RUN TestTimer --- PASS: TestTimer (0.00s) === RUN TestRemoveMetricLabelValue @@ -1097,7 +1133,7 @@ === RUN TestMatchingKind --- PASS: TestMatchingKind (0.00s) === RUN TestDuplicateMetric ---- PASS: TestDuplicateMetric (0.00s) +--- PASS: TestDuplicateMetric (0.08s) === RUN TestAddMetricDifferentType --- PASS: TestAddMetricDifferentType (0.00s) === RUN TestExpireOldDatum @@ -1107,7 +1143,7 @@ === RUN TestExpireManyMetrics --- PASS: TestExpireManyMetrics (0.00s) PASS -ok github.com/google/mtail/internal/metrics 0.040s +ok github.com/google/mtail/internal/metrics 0.392s === RUN TestDatumSetAndValue --- PASS: TestDatumSetAndValue (0.00s) === RUN TestMarshalJSON @@ -1119,14 +1155,14 @@ === RUN TestMakeBucket --- PASS: TestMakeBucket (0.00s) PASS -ok github.com/google/mtail/internal/metrics/datum 0.006s +ok github.com/google/mtail/internal/metrics/datum 0.092s ? github.com/google/mtail/internal/runtime/compiler/position [no test files] === RUN TestBuildInfo --- PASS: TestBuildInfo (0.00s) === RUN TestBasicTail ---- PASS: TestBasicTail (0.03s) +--- PASS: TestBasicTail (0.60s) === RUN TestNewLogDoesNotMatchIsIgnored ---- PASS: TestNewLogDoesNotMatchIsIgnored (0.01s) +--- PASS: TestNewLogDoesNotMatchIsIgnored (0.02s) === RUN TestBadProgramFailsCompilation --- PASS: TestBadProgramFailsCompilation (0.00s) === RUN TestExamplePrograms @@ -1141,18 +1177,18 @@ === RUN TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog === RUN TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log === RUN TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log ---- PASS: TestExamplePrograms (1.46s) - --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.01s) - --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.02s) - --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (1.23s) - --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.11s) - --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.01s) - --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.01s) - --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.01s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.02s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.02s) - --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.01s) - --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.03s) +--- PASS: TestExamplePrograms (9.74s) + --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.06s) + --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.10s) + --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (8.21s) + --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.08s) + --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.11s) + --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.04s) + --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.13s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.30s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.22s) + --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.16s) + --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.32s) === RUN TestCompileExamplePrograms === RUN TestCompileExamplePrograms/apache_combined.mtail === RUN TestCompileExamplePrograms/apache_common.mtail @@ -1171,24 +1207,24 @@ === RUN TestCompileExamplePrograms/sftp.mtail === RUN TestCompileExamplePrograms/timer.mtail === RUN TestCompileExamplePrograms/vsftpd.mtail ---- PASS: TestCompileExamplePrograms (0.27s) - --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/histogram.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/linecount.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/nocode.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/postfix.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/rails.mtail (0.08s) - --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/sftp.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/timer.mtail (0.07s) - --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.01s) +--- PASS: TestCompileExamplePrograms (1.05s) + --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.05s) + --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.06s) + --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.08s) + --- PASS: TestCompileExamplePrograms/histogram.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/linecount.mtail (0.01s) + --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.13s) + --- PASS: TestCompileExamplePrograms/nocode.mtail (0.01s) + --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.09s) + --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.04s) + --- PASS: TestCompileExamplePrograms/postfix.mtail (0.19s) + --- PASS: TestCompileExamplePrograms/rails.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.06s) + --- PASS: TestCompileExamplePrograms/sftp.mtail (0.10s) + --- PASS: TestCompileExamplePrograms/timer.mtail (0.00s) + --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.14s) === RUN TestFilePipeStreamComparison === RUN TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -1201,18 +1237,18 @@ === RUN TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog === RUN TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log === RUN TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log ---- PASS: TestFilePipeStreamComparison (2.12s) - --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.06s) - --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (1.96s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/apache_common.mtail_on_testdata/apache-common.log (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.02s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.01s) - --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.02s) +--- PASS: TestFilePipeStreamComparison (14.48s) + --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.09s) + --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.10s) + --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (13.10s) + --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.11s) + --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.09s) + --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.06s) + --- PASS: TestFilePipeStreamComparison/examples/apache_common.mtail_on_testdata/apache-common.log (0.07s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.15s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.15s) + --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.23s) + --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.33s) === RUN TestFileSocketStreamComparison === RUN TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log === RUN TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log @@ -1236,29 +1272,29 @@ === RUN TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog === RUN TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log === RUN TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log ---- PASS: TestFileSocketStreamComparison (2.92s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (1.35s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.00s) - --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (1.35s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unix://testdata/apache-combined.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unix://testdata/apache-common.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.01s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.02s) +--- PASS: TestFileSocketStreamComparison (26.58s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.11s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.14s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (11.65s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.14s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.11s) + --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.08s) + --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.09s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.21s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.17s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.14s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.25s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.11s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.15s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (12.22s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.06s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.06s) + --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unix://testdata/apache-combined.log (0.03s) + --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unix://testdata/apache-common.log (0.07s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_log (0.12s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.15s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.08s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.42s) === RUN TestExecMtail exec_integration_test.go:32: mtail binary not found, skipping exec_integration_test.go:33: @@ -1266,61 +1302,61 @@ === RUN TestLogDeletion --- PASS: TestLogDeletion (0.02s) === RUN TestGlobBeforeStart ---- PASS: TestGlobBeforeStart (0.01s) +--- PASS: TestGlobBeforeStart (1.66s) === RUN TestGlobAfterStart --- PASS: TestGlobAfterStart (0.01s) === RUN TestGlobIgnoreFolder ---- PASS: TestGlobIgnoreFolder (0.01s) +--- PASS: TestGlobIgnoreFolder (1.14s) === RUN TestFilenameRegexIgnore ---- PASS: TestFilenameRegexIgnore (0.01s) +--- PASS: TestFilenameRegexIgnore (1.86s) === RUN TestGlobRelativeAfterStart ---- PASS: TestGlobRelativeAfterStart (0.04s) +--- PASS: TestGlobRelativeAfterStart (0.38s) === RUN TestLogSoftLinkChange === RUN TestLogSoftLinkChange/race_simulation_disabled === RUN TestLogSoftLinkChange/race_simulation_enabled ---- PASS: TestLogSoftLinkChange (0.08s) - --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.03s) - --- PASS: TestLogSoftLinkChange/race_simulation_enabled (0.05s) +--- PASS: TestLogSoftLinkChange (0.26s) + --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.14s) + --- PASS: TestLogSoftLinkChange/race_simulation_enabled (0.11s) === RUN TestLogRotation === RUN TestLogRotation/race_simulation_disabled === RUN TestLogRotation/race_simulation_enabled ---- PASS: TestLogRotation (0.06s) - --- PASS: TestLogRotation/race_simulation_disabled (0.02s) - --- PASS: TestLogRotation/race_simulation_enabled (0.04s) +--- PASS: TestLogRotation (0.14s) + --- PASS: TestLogRotation/race_simulation_disabled (0.06s) + --- PASS: TestLogRotation/race_simulation_enabled (0.08s) === RUN TestLogTruncation ---- PASS: TestLogTruncation (0.03s) +--- PASS: TestLogTruncation (0.08s) === RUN TestPollLogPathPatterns ---- PASS: TestPollLogPathPatterns (0.03s) +--- PASS: TestPollLogPathPatterns (0.17s) === RUN TestMultipleLinesInOneWrite ---- PASS: TestMultipleLinesInOneWrite (0.02s) +--- PASS: TestMultipleLinesInOneWrite (0.03s) === RUN TestPartialLineRead ---- PASS: TestPartialLineRead (0.02s) +--- PASS: TestPartialLineRead (0.03s) === RUN TestPermissionDeniedOnLog ---- PASS: TestPermissionDeniedOnLog (0.01s) +--- PASS: TestPermissionDeniedOnLog (0.02s) === RUN TestNewProg ---- PASS: TestNewProg (0.01s) +--- PASS: TestNewProg (0.02s) === RUN TestProgramReloadNoDuplicateMetrics ---- PASS: TestProgramReloadNoDuplicateMetrics (0.05s) +--- PASS: TestProgramReloadNoDuplicateMetrics (0.11s) === RUN TestProgramUnloadIfDeleted ---- PASS: TestProgramUnloadIfDeleted (0.03s) +--- PASS: TestProgramUnloadIfDeleted (0.04s) === RUN TestReadFromPipe --- PASS: TestReadFromPipe (0.01s) === RUN TestReadFromSocket === RUN TestReadFromSocket/unix === RUN TestReadFromSocket/unixgram ---- PASS: TestReadFromSocket (0.05s) - --- PASS: TestReadFromSocket/unix (0.02s) - --- PASS: TestReadFromSocket/unixgram (0.02s) +--- PASS: TestReadFromSocket (0.09s) + --- PASS: TestReadFromSocket/unix (0.06s) + --- PASS: TestReadFromSocket/unixgram (0.03s) === RUN TestRelativeLog ---- PASS: TestRelativeLog (0.02s) +--- PASS: TestRelativeLog (0.05s) === RUN TestBasicUNIXSockets ---- PASS: TestBasicUNIXSockets (0.00s) +--- PASS: TestBasicUNIXSockets (0.01s) PASS -ok github.com/google/mtail/internal/mtail 7.342s +ok github.com/google/mtail/internal/mtail 58.735s === RUN TestReadTestData ---- PASS: TestReadTestData (0.00s) +--- PASS: TestReadTestData (0.03s) PASS -ok github.com/google/mtail/internal/mtail/golden 0.013s +ok github.com/google/mtail/internal/mtail/golden 0.081s === RUN TestRuntimeEndToEnd === RUN TestRuntimeEndToEnd/single-dash-parseint === RUN TestRuntimeEndToEnd/histogram @@ -1344,29 +1380,29 @@ === RUN TestRuntimeEndToEnd/subst_integer === RUN TestRuntimeEndToEnd/regexp_replace === RUN TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr ---- PASS: TestRuntimeEndToEnd (0.30s) - --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.01s) - --- PASS: TestRuntimeEndToEnd/histogram (0.02s) - --- PASS: TestRuntimeEndToEnd/numbers (0.01s) - --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.01s) - --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.01s) - --- PASS: TestRuntimeEndToEnd/add_assign_float (0.01s) - --- PASS: TestRuntimeEndToEnd/decorator (0.01s) - --- PASS: TestRuntimeEndToEnd/else (0.02s) - --- PASS: TestRuntimeEndToEnd/otherwise (0.01s) - --- PASS: TestRuntimeEndToEnd/types (0.02s) - --- PASS: TestRuntimeEndToEnd/filename (0.01s) - --- PASS: TestRuntimeEndToEnd/logical_operators (0.01s) - --- PASS: TestRuntimeEndToEnd/strcat (0.01s) - --- PASS: TestRuntimeEndToEnd/typed-comparison (0.01s) - --- PASS: TestRuntimeEndToEnd/match-expression (0.01s) - --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.01s) - --- PASS: TestRuntimeEndToEnd/stringy (0.01s) - --- PASS: TestRuntimeEndToEnd/ip-addr (0.01s) - --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.01s) - --- PASS: TestRuntimeEndToEnd/subst_integer (0.01s) - --- PASS: TestRuntimeEndToEnd/regexp_replace (0.01s) - --- PASS: TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr (0.01s) +--- PASS: TestRuntimeEndToEnd (0.75s) + --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.02s) + --- PASS: TestRuntimeEndToEnd/histogram (0.05s) + --- PASS: TestRuntimeEndToEnd/numbers (0.02s) + --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.02s) + --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.14s) + --- PASS: TestRuntimeEndToEnd/add_assign_float (0.02s) + --- PASS: TestRuntimeEndToEnd/decorator (0.03s) + --- PASS: TestRuntimeEndToEnd/else (0.03s) + --- PASS: TestRuntimeEndToEnd/otherwise (0.06s) + --- PASS: TestRuntimeEndToEnd/types (0.05s) + --- PASS: TestRuntimeEndToEnd/filename (0.02s) + --- PASS: TestRuntimeEndToEnd/logical_operators (0.03s) + --- PASS: TestRuntimeEndToEnd/strcat (0.02s) + --- PASS: TestRuntimeEndToEnd/typed-comparison (0.03s) + --- PASS: TestRuntimeEndToEnd/match-expression (0.03s) + --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.04s) + --- PASS: TestRuntimeEndToEnd/stringy (0.02s) + --- PASS: TestRuntimeEndToEnd/ip-addr (0.02s) + --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.02s) + --- PASS: TestRuntimeEndToEnd/subst_integer (0.03s) + --- PASS: TestRuntimeEndToEnd/regexp_replace (0.02s) + --- PASS: TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr (0.02s) === RUN TestNewRuntime --- PASS: TestNewRuntime (0.00s) === RUN TestNewRuntimeErrors @@ -1374,15 +1410,15 @@ === RUN TestCompileAndRun --- PASS: TestCompileAndRun (0.00s) === RUN TestLoadProg ---- PASS: TestLoadProg (0.01s) +--- PASS: TestLoadProg (0.00s) PASS -ok github.com/google/mtail/internal/runtime 0.316s +ok github.com/google/mtail/internal/runtime 0.828s === RUN TestOpcodeHasString --- PASS: TestOpcodeHasString (0.00s) === RUN TestInstrString --- PASS: TestInstrString (0.00s) PASS -ok github.com/google/mtail/internal/runtime/code 0.007s +ok github.com/google/mtail/internal/runtime/code 0.092s === RUN TestCompileParserError --- PASS: TestCompileParserError (0.00s) === RUN TestCompileCheckerError @@ -1390,13 +1426,13 @@ === RUN TestCompileCodegen --- PASS: TestCompileCodegen (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler 0.012s +ok github.com/google/mtail/internal/runtime/compiler 0.076s === RUN TestWalkPanicsOnUnknown --- PASS: TestWalkPanicsOnUnknown (0.00s) === RUN TestAstReplacement --- PASS: TestAstReplacement (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/ast 0.008s +ok github.com/google/mtail/internal/runtime/compiler/ast 0.084s === RUN TestCheckInvalidPrograms === RUN TestCheckInvalidPrograms/undefined_named_capture_group === RUN TestCheckInvalidPrograms/out_of_bounds_capref @@ -1433,8 +1469,8 @@ === RUN TestCheckInvalidPrograms/dec_non_var === RUN TestCheckInvalidPrograms/cmp_to_None === RUN TestCheckInvalidPrograms/negate_None ---- PASS: TestCheckInvalidPrograms (0.01s) - --- PASS: TestCheckInvalidPrograms/undefined_named_capture_group (0.00s) +--- PASS: TestCheckInvalidPrograms (0.09s) + --- PASS: TestCheckInvalidPrograms/undefined_named_capture_group (0.02s) --- PASS: TestCheckInvalidPrograms/out_of_bounds_capref (0.00s) --- PASS: TestCheckInvalidPrograms/undefined_decorator (0.00s) --- PASS: TestCheckInvalidPrograms/undefined_identifier (0.00s) @@ -1442,7 +1478,7 @@ --- PASS: TestCheckInvalidPrograms/invalid_regex_2 (0.00s) --- PASS: TestCheckInvalidPrograms/invalid_regex_3 (0.00s) --- PASS: TestCheckInvalidPrograms/duplicate_declaration (0.00s) - --- PASS: TestCheckInvalidPrograms/indexedExpr_parameter_count (0.00s) + --- PASS: TestCheckInvalidPrograms/indexedExpr_parameter_count (0.02s) --- PASS: TestCheckInvalidPrograms/indexedExpr_binary_expression (0.00s) --- PASS: TestCheckInvalidPrograms/builtin_parameter_mismatch (0.00s) --- PASS: TestCheckInvalidPrograms/bad_strptime_format (0.00s) @@ -1454,7 +1490,7 @@ --- PASS: TestCheckInvalidPrograms/def_with_two_nexts (0.00s) --- PASS: TestCheckInvalidPrograms/counter_with_buckets (0.00s) --- PASS: TestCheckInvalidPrograms/next_outside_of_decorator (0.00s) - --- PASS: TestCheckInvalidPrograms/use_decorator_in_decorator (0.00s) + --- PASS: TestCheckInvalidPrograms/use_decorator_in_decorator (0.02s) --- PASS: TestCheckInvalidPrograms/delete_incorrect_object (0.00s) --- PASS: TestCheckInvalidPrograms/pattern_fragment_plus_anything (0.00s) --- PASS: TestCheckInvalidPrograms/recursive_pattern_fragment (0.00s) @@ -1502,7 +1538,7 @@ === RUN TestCheckValidPrograms/negative_numbers_in_capture_groups === RUN TestCheckValidPrograms/substitution === RUN TestCheckValidPrograms/regexp_subst ---- PASS: TestCheckValidPrograms (0.00s) +--- PASS: TestCheckValidPrograms (0.03s) --- PASS: TestCheckValidPrograms/capture_group (0.00s) --- PASS: TestCheckValidPrograms/shadowed_positionals (0.00s) --- PASS: TestCheckValidPrograms/sibling_positionals (0.00s) @@ -1524,7 +1560,7 @@ --- PASS: TestCheckValidPrograms/concat_expr_1 (0.00s) --- PASS: TestCheckValidPrograms/concat_expr_2 (0.00s) --- PASS: TestCheckValidPrograms/match_expression_3 (0.00s) - --- PASS: TestCheckValidPrograms/match_expr_4 (0.00s) + --- PASS: TestCheckValidPrograms/match_expr_4 (0.03s) --- PASS: TestCheckValidPrograms/decorator_scopes (0.00s) --- PASS: TestCheckValidPrograms/concat_with_add_assign (0.00s) --- PASS: TestCheckValidPrograms/decrement (0.00s) @@ -1544,7 +1580,7 @@ --- PASS: TestCheckTypeExpressions/Int_+_Float_->_Float (0.00s) --- PASS: TestCheckTypeExpressions/⍺_+_Float_->_Float (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/checker 0.021s +ok github.com/google/mtail/internal/runtime/compiler/checker 0.184s === RUN TestCodeGenFromSource === RUN TestCodeGenFromSource/simple_line_counter === RUN TestCodeGenFromSource/count_a @@ -1597,7 +1633,7 @@ === RUN TestCodeGenFromSource/negative_numbers_in_capture_groups === RUN TestCodeGenFromSource/substitution === RUN TestCodeGenFromSource/const_term_as_pattern ---- PASS: TestCodeGenFromSource (0.01s) +--- PASS: TestCodeGenFromSource (0.09s) --- PASS: TestCodeGenFromSource/simple_line_counter (0.00s) --- PASS: TestCodeGenFromSource/count_a (0.00s) --- PASS: TestCodeGenFromSource/strptime_and_capref (0.00s) @@ -1610,7 +1646,7 @@ --- PASS: TestCodeGenFromSource/cond_expr_ge (0.00s) --- PASS: TestCodeGenFromSource/cond_expr_ne (0.00s) --- PASS: TestCodeGenFromSource/nested_cond (0.00s) - --- PASS: TestCodeGenFromSource/deco (0.00s) + --- PASS: TestCodeGenFromSource/deco (0.01s) --- PASS: TestCodeGenFromSource/length (0.00s) --- PASS: TestCodeGenFromSource/bitwise (0.00s) --- PASS: TestCodeGenFromSource/pow (0.00s) @@ -1632,7 +1668,7 @@ --- PASS: TestCodeGenFromSource/int_to_string (0.00s) --- PASS: TestCodeGenFromSource/nested_comparisons (0.00s) --- PASS: TestCodeGenFromSource/string_concat (0.00s) - --- PASS: TestCodeGenFromSource/add_assign_float (0.00s) + --- PASS: TestCodeGenFromSource/add_assign_float (0.01s) --- PASS: TestCodeGenFromSource/match_expression (0.00s) --- PASS: TestCodeGenFromSource/negative_match_expression (0.00s) --- PASS: TestCodeGenFromSource/capref_used_in_def (0.00s) @@ -1640,13 +1676,13 @@ --- PASS: TestCodeGenFromSource/binop_compare_type_conversion (0.00s) --- PASS: TestCodeGenFromSource/set_string (0.00s) --- PASS: TestCodeGenFromSource/concat_to_text (0.00s) - --- PASS: TestCodeGenFromSource/decrement (0.00s) + --- PASS: TestCodeGenFromSource/decrement (0.02s) --- PASS: TestCodeGenFromSource/capref_and_settime (0.00s) --- PASS: TestCodeGenFromSource/cast_to_self (0.00s) --- PASS: TestCodeGenFromSource/stop (0.00s) --- PASS: TestCodeGenFromSource/stop_inside (0.00s) --- PASS: TestCodeGenFromSource/nested_decorators (0.00s) - --- PASS: TestCodeGenFromSource/negative_numbers_in_capture_groups (0.00s) + --- PASS: TestCodeGenFromSource/negative_numbers_in_capture_groups (0.01s) --- PASS: TestCodeGenFromSource/substitution (0.00s) --- PASS: TestCodeGenFromSource/const_term_as_pattern (0.00s) === RUN TestCodeGenFromAST @@ -1656,23 +1692,23 @@ --- PASS: TestCodeGenFromAST/subst (0.00s) --- PASS: TestCodeGenFromAST/regexp_subst (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/codegen 0.020s +ok github.com/google/mtail/internal/runtime/compiler/codegen 0.163s === RUN TestNilErrorPosition --- PASS: TestNilErrorPosition (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/errors 0.005s +ok github.com/google/mtail/internal/runtime/compiler/errors 0.048s === RUN TestOptimiser === RUN TestOptimiser/int_add === RUN TestOptimiser/float_mul === RUN TestOptimiser/int_float_pow === RUN TestOptimiser/float_int_mod === RUN TestOptimiser/nested_ops ---- PASS: TestOptimiser (0.00s) +--- PASS: TestOptimiser (0.01s) --- PASS: TestOptimiser/int_add (0.00s) --- PASS: TestOptimiser/float_mul (0.00s) --- PASS: TestOptimiser/int_float_pow (0.00s) --- PASS: TestOptimiser/float_int_mod (0.00s) - --- PASS: TestOptimiser/nested_ops (0.00s) + --- PASS: TestOptimiser/nested_ops (0.01s) === RUN TestOptimiserErrors === RUN TestOptimiserErrors/integer_divide_by_zero === RUN TestOptimiserErrors/float_divide_by_zero @@ -1686,9 +1722,9 @@ === RUN TestConstFoldQuickIntComm === RUN TestConstFoldQuickIntComm/add === RUN TestConstFoldQuickIntComm/mul ---- PASS: TestConstFoldQuickIntComm (0.00s) +--- PASS: TestConstFoldQuickIntComm (0.02s) --- PASS: TestConstFoldQuickIntComm/add (0.00s) - --- PASS: TestConstFoldQuickIntComm/mul (0.00s) + --- PASS: TestConstFoldQuickIntComm/mul (0.02s) === RUN TestConstFoldQuickFloatComm === RUN TestConstFoldQuickFloatComm/add === RUN TestConstFoldQuickFloatComm/mul @@ -1696,17 +1732,17 @@ --- PASS: TestConstFoldQuickFloatComm/add (0.00s) --- PASS: TestConstFoldQuickFloatComm/mul (0.00s) === RUN TestConstFoldQuickMixedComm -=== RUN TestConstFoldQuickMixedComm/mul === RUN TestConstFoldQuickMixedComm/add ---- PASS: TestConstFoldQuickMixedComm (0.00s) - --- PASS: TestConstFoldQuickMixedComm/mul (0.00s) - --- PASS: TestConstFoldQuickMixedComm/add (0.00s) +=== RUN TestConstFoldQuickMixedComm/mul +--- PASS: TestConstFoldQuickMixedComm (0.05s) + --- PASS: TestConstFoldQuickMixedComm/add (0.01s) + --- PASS: TestConstFoldQuickMixedComm/mul (0.03s) === RUN TestConstFoldQuickIntAddSub ---- PASS: TestConstFoldQuickIntAddSub (0.00s) +--- PASS: TestConstFoldQuickIntAddSub (0.01s) === RUN TestConstFoldQuickFloatAddSub --- PASS: TestConstFoldQuickFloatAddSub (0.00s) === RUN TestConstFoldQuickMixedAddSub ---- PASS: TestConstFoldQuickMixedAddSub (0.00s) +--- PASS: TestConstFoldQuickMixedAddSub (0.01s) === RUN TestConstFoldQuickFloatMulDiv --- PASS: TestConstFoldQuickFloatMulDiv (0.00s) === RUN TestConstFoldQuickIntModAddition @@ -1716,7 +1752,7 @@ === RUN TestConstFoldQuickMixedPowProduct --- PASS: TestConstFoldQuickMixedPowProduct (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/opt 0.022s +ok github.com/google/mtail/internal/runtime/compiler/opt 0.250s === RUN TestLex === RUN TestLex/empty === RUN TestLex/spaces @@ -1743,12 +1779,12 @@ === RUN TestLex/unexpected_char === RUN TestLex/unterminated_regex === RUN TestLex/unterminated_quoted_string ---- PASS: TestLex (0.00s) +--- PASS: TestLex (0.03s) --- PASS: TestLex/empty (0.00s) --- PASS: TestLex/spaces (0.00s) --- PASS: TestLex/newlines (0.00s) --- PASS: TestLex/comment (0.00s) - --- PASS: TestLex/comment_not_at_col_1 (0.00s) + --- PASS: TestLex/comment_not_at_col_1 (0.01s) --- PASS: TestLex/punctuation (0.00s) --- PASS: TestLex/operators (0.00s) --- PASS: TestLex/keywords (0.00s) @@ -1833,7 +1869,7 @@ === RUN TestParserRoundTrip/stop === RUN TestParserRoundTrip/substitution === RUN TestParserRoundTrip/pattern_in_arg_expr_list ---- PASS: TestParserRoundTrip (0.01s) +--- PASS: TestParserRoundTrip (0.05s) --- PASS: TestParserRoundTrip/empty (0.00s) --- PASS: TestParserRoundTrip/newline (0.00s) --- PASS: TestParserRoundTrip/declare_counter (0.00s) @@ -1873,7 +1909,7 @@ --- PASS: TestParserRoundTrip/len (0.00s) --- PASS: TestParserRoundTrip/def_and_next (0.00s) --- PASS: TestParserRoundTrip/const (0.00s) - --- PASS: TestParserRoundTrip/bitwise (0.00s) + --- PASS: TestParserRoundTrip/bitwise (0.02s) --- PASS: TestParserRoundTrip/logical (0.00s) --- PASS: TestParserRoundTrip/floats (0.00s) --- PASS: TestParserRoundTrip/simple_otherwise_action (0.00s) @@ -1912,7 +1948,7 @@ === RUN TestParseInvalidPrograms/pattern_without_block === RUN TestParseInvalidPrograms/paired_pattern_without_block === RUN TestParseInvalidPrograms/dimensioned_limit_per_dimension ---- PASS: TestParseInvalidPrograms (0.00s) +--- PASS: TestParseInvalidPrograms (0.02s) --- PASS: TestParseInvalidPrograms/unknown_character (0.00s) --- PASS: TestParseInvalidPrograms/unterminated_regex (0.00s) --- PASS: TestParseInvalidPrograms/unterminated_string (0.00s) @@ -1940,17 +1976,17 @@ === RUN TestKindHasString --- PASS: TestKindHasString (0.00s) === RUN TestTokenString ---- PASS: TestTokenString (0.00s) +--- PASS: TestTokenString (0.07s) PASS -ok github.com/google/mtail/internal/runtime/compiler/parser 0.023s +ok github.com/google/mtail/internal/runtime/compiler/parser 0.264s === RUN TestInsertLookup ---- PASS: TestInsertLookup (0.00s) +--- PASS: TestInsertLookup (0.02s) === RUN TestInsertLookupQuick ---- PASS: TestInsertLookupQuick (1.31s) +--- PASS: TestInsertLookupQuick (13.75s) === RUN TestNestedScope --- PASS: TestNestedScope (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/symbol 1.317s +ok github.com/google/mtail/internal/runtime/compiler/symbol 13.839s === RUN TestTypeUnification === RUN TestTypeUnification/None_None === RUN TestTypeUnification/String_String @@ -1990,7 +2026,7 @@ === RUN TestTypeUnification/Int_Int|Float === RUN TestTypeUnification/Int|Float_Float === RUN TestTypeUnification/Float_Int|Float ---- PASS: TestTypeUnification (0.00s) +--- PASS: TestTypeUnification (0.01s) --- PASS: TestTypeUnification/None_None (0.00s) --- PASS: TestTypeUnification/String_String (0.00s) --- PASS: TestTypeUnification/Int_Int (0.00s) @@ -2062,7 +2098,7 @@ === RUN TestAsTypeError --- PASS: TestAsTypeError (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/types 0.009s +ok github.com/google/mtail/internal/runtime/compiler/types 0.068s === RUN TestInstrs === RUN TestInstrs/match === RUN TestInstrs/cmp_lt @@ -2222,35 +2258,35 @@ === RUN TestTimestampInstr --- PASS: TestTimestampInstr (0.00s) PASS -ok github.com/google/mtail/internal/runtime/vm 0.017s +ok github.com/google/mtail/internal/runtime/vm 0.125s === RUN TestTail --- PASS: TestTail (0.00s) === RUN TestTailErrors --- PASS: TestTailErrors (0.00s) === RUN TestHandleLogUpdate ---- PASS: TestHandleLogUpdate (0.01s) +--- PASS: TestHandleLogUpdate (0.30s) === RUN TestHandleLogTruncate ---- PASS: TestHandleLogTruncate (0.01s) +--- PASS: TestHandleLogTruncate (0.03s) === RUN TestHandleLogUpdatePartialLine ---- PASS: TestHandleLogUpdatePartialLine (0.01s) +--- PASS: TestHandleLogUpdatePartialLine (0.04s) === RUN TestTailerUnreadableFile ---- PASS: TestTailerUnreadableFile (0.00s) +--- PASS: TestTailerUnreadableFile (0.02s) === RUN TestTailerInitErrors --- PASS: TestTailerInitErrors (0.00s) === RUN TestTailExpireStaleHandles tail_test.go:227: need to set lastRead on logstream to inject condition --- SKIP: TestTailExpireStaleHandles (0.00s) === RUN TestTailerOpenRetries ---- PASS: TestTailerOpenRetries (0.00s) +--- PASS: TestTailerOpenRetries (0.02s) PASS -ok github.com/google/mtail/internal/tailer 0.041s +ok github.com/google/mtail/internal/tailer 0.500s === RUN TestNewErrors --- PASS: TestNewErrors (0.00s) === RUN TestDgramStreamReadCompletedBecauseSocketClosed === RUN TestDgramStreamReadCompletedBecauseSocketClosed/unixgram === RUN TestDgramStreamReadCompletedBecauseSocketClosed/udp ---- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.00s) - --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.00s) +--- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.05s) + --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.05s) --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/udp (0.00s) === RUN TestDgramStreamReadCompletedBecauseCancel === RUN TestDgramStreamReadCompletedBecauseCancel/unixgram @@ -2259,21 +2295,21 @@ --- PASS: TestDgramStreamReadCompletedBecauseCancel/unixgram (0.00s) --- PASS: TestDgramStreamReadCompletedBecauseCancel/udp (0.00s) === RUN TestFileStreamRead ---- PASS: TestFileStreamRead (0.00s) +--- PASS: TestFileStreamRead (0.16s) === RUN TestFileStreamReadNonSingleByteEnd ---- PASS: TestFileStreamReadNonSingleByteEnd (0.01s) +--- PASS: TestFileStreamReadNonSingleByteEnd (0.14s) === RUN TestStreamDoesntBreakOnCorruptRune ---- PASS: TestStreamDoesntBreakOnCorruptRune (0.00s) +--- PASS: TestStreamDoesntBreakOnCorruptRune (0.02s) === RUN TestFileStreamTruncation ---- PASS: TestFileStreamTruncation (0.01s) +--- PASS: TestFileStreamTruncation (0.03s) === RUN TestFileStreamFinishedBecauseCancel ---- PASS: TestFileStreamFinishedBecauseCancel (0.00s) +--- PASS: TestFileStreamFinishedBecauseCancel (0.02s) === RUN TestFileStreamPartialRead --- PASS: TestFileStreamPartialRead (0.01s) === RUN TestFileStreamRotation ---- PASS: TestFileStreamRotation (0.01s) +--- PASS: TestFileStreamRotation (0.03s) === RUN TestFileStreamURL ---- PASS: TestFileStreamURL (0.00s) +--- PASS: TestFileStreamURL (0.02s) === RUN TestFileStreamOpenFailure --- PASS: TestFileStreamOpenFailure (0.00s) === RUN TestPipeStreamReadCompletedBecauseClosed @@ -2285,9 +2321,9 @@ === RUN TestSocketStreamReadCompletedBecauseSocketClosed === RUN TestSocketStreamReadCompletedBecauseSocketClosed/unix === RUN TestSocketStreamReadCompletedBecauseSocketClosed/tcp ---- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.00s) +--- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.01s) --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/unix (0.00s) - --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/tcp (0.00s) + --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/tcp (0.01s) === RUN TestSocketStreamReadCompletedBecauseCancel === RUN TestSocketStreamReadCompletedBecauseCancel/unix === RUN TestSocketStreamReadCompletedBecauseCancel/tcp @@ -2295,7 +2331,7 @@ --- PASS: TestSocketStreamReadCompletedBecauseCancel/unix (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseCancel/tcp (0.00s) PASS -ok github.com/google/mtail/internal/tailer/logstream 0.069s +ok github.com/google/mtail/internal/tailer/logstream 0.551s === RUN TestDoOrTimeoutNeverOK --- PASS: TestDoOrTimeoutNeverOK (0.01s) === RUN TestDoOrTimeoutAlwaysOK @@ -2305,7 +2341,7 @@ === RUN TestDoOrTimeoutAlwaysErr --- PASS: TestDoOrTimeoutAlwaysErr (0.00s) PASS -ok github.com/google/mtail/internal/testutil 0.071s +ok github.com/google/mtail/internal/testutil 0.118s === RUN TestTestWakerWakes --- PASS: TestTestWakerWakes (0.00s) === RUN TestTestWakerTwoWakees @@ -2315,11 +2351,11 @@ === RUN TestTimedWakerWakes --- PASS: TestTimedWakerWakes (0.01s) PASS -ok github.com/google/mtail/internal/waker 0.016s +ok github.com/google/mtail/internal/waker 0.056s # Only run race tests on amd64. test "amd64" != amd64 || \ dh_auto_test -- -timeout 20m -race -tags=integration - cd build && go test -vet=off -v -p 42 -timeout 20m -race -tags=integration github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker + cd build && go test -vet=off -v -p 20 -timeout 20m -race -tags=integration github.com/google/mtail/cmd/mfmt github.com/google/mtail/cmd/mtail github.com/google/mtail/internal/exporter github.com/google/mtail/internal/logline github.com/google/mtail/internal/metrics github.com/google/mtail/internal/metrics/datum github.com/google/mtail/internal/mtail github.com/google/mtail/internal/mtail/golden github.com/google/mtail/internal/runtime github.com/google/mtail/internal/runtime/code github.com/google/mtail/internal/runtime/compiler github.com/google/mtail/internal/runtime/compiler/ast github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler/codegen github.com/google/mtail/internal/runtime/compiler/errors github.com/google/mtail/internal/runtime/compiler/opt github.com/google/mtail/internal/runtime/compiler/parser github.com/google/mtail/internal/runtime/compiler/position github.com/google/mtail/internal/runtime/compiler/symbol github.com/google/mtail/internal/runtime/compiler/types github.com/google/mtail/internal/runtime/vm github.com/google/mtail/internal/tailer github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil github.com/google/mtail/internal/waker ? github.com/google/mtail/cmd/mfmt [no test files] ? github.com/google/mtail/cmd/mtail [no test files] ? github.com/google/mtail/internal/logline [no test files] @@ -2330,7 +2366,7 @@ === RUN TestMetricToCollectd --- PASS: TestMetricToCollectd (0.00s) === RUN TestMetricToGraphite ---- PASS: TestMetricToGraphite (0.00s) +--- PASS: TestMetricToGraphite (0.01s) === RUN TestMetricToStatsd --- PASS: TestMetricToStatsd (0.00s) === RUN TestHandleGraphite @@ -2344,8 +2380,8 @@ === RUN TestHandleJSON/single === RUN TestHandleJSON/dimensioned === RUN TestHandleJSON/histogram ---- PASS: TestHandleJSON (0.00s) - --- PASS: TestHandleJSON/empty (0.00s) +--- PASS: TestHandleJSON (0.02s) + --- PASS: TestHandleJSON/empty (0.01s) --- PASS: TestHandleJSON/single (0.00s) --- PASS: TestHandleJSON/dimensioned (0.00s) --- PASS: TestHandleJSON/histogram (0.00s) @@ -2362,7 +2398,7 @@ === RUN TestHandlePrometheus/2_help_with_label === RUN TestHandlePrometheus/histo === RUN TestHandlePrometheus/histo-count-eq-inf ---- PASS: TestHandlePrometheus (0.01s) +--- PASS: TestHandlePrometheus (0.04s) --- PASS: TestHandlePrometheus/empty (0.00s) --- PASS: TestHandlePrometheus/single (0.00s) --- PASS: TestHandlePrometheus/with_prog_label (0.00s) @@ -2373,8 +2409,8 @@ --- PASS: TestHandlePrometheus/quotes (0.00s) --- PASS: TestHandlePrometheus/help (0.00s) --- PASS: TestHandlePrometheus/2_help_with_label (0.00s) - --- PASS: TestHandlePrometheus/histo (0.00s) - --- PASS: TestHandlePrometheus/histo-count-eq-inf (0.00s) + --- PASS: TestHandlePrometheus/histo (0.01s) + --- PASS: TestHandlePrometheus/histo-count-eq-inf (0.01s) === RUN TestWritePrometheus === RUN TestWritePrometheus/empty === RUN TestWritePrometheus/single @@ -2388,14 +2424,13 @@ === RUN TestHandleVarz/single === RUN TestHandleVarz/dimensioned === RUN TestHandleVarz/text ---- PASS: TestHandleVarz (0.00s) +--- PASS: TestHandleVarz (0.02s) --- PASS: TestHandleVarz/empty (0.00s) --- PASS: TestHandleVarz/single (0.00s) --- PASS: TestHandleVarz/dimensioned (0.00s) --- PASS: TestHandleVarz/text (0.00s) PASS -ok github.com/google/mtail/internal/exporter 1.040s -? github.com/google/mtail/internal/runtime/compiler/position [no test files] +ok github.com/google/mtail/internal/exporter 1.292s === RUN TestKindType --- PASS: TestKindType (0.00s) === RUN TestScalarMetric @@ -2413,7 +2448,7 @@ === RUN TestAppendLabelValue --- PASS: TestAppendLabelValue (0.00s) === RUN TestMetricJSONRoundTrip ---- PASS: TestMetricJSONRoundTrip (0.23s) +--- PASS: TestMetricJSONRoundTrip (0.90s) === RUN TestTimer --- PASS: TestTimer (0.00s) === RUN TestRemoveMetricLabelValue @@ -2433,7 +2468,7 @@ === RUN TestExpireManyMetrics --- PASS: TestExpireManyMetrics (0.00s) PASS -ok github.com/google/mtail/internal/metrics 1.265s +ok github.com/google/mtail/internal/metrics 2.072s === RUN TestDatumSetAndValue --- PASS: TestDatumSetAndValue (0.00s) === RUN TestMarshalJSON @@ -2445,15 +2480,16 @@ === RUN TestMakeBucket --- PASS: TestMakeBucket (0.00s) PASS -ok github.com/google/mtail/internal/metrics/datum 1.022s +ok github.com/google/mtail/internal/metrics/datum 1.140s +? github.com/google/mtail/internal/runtime/compiler/position [no test files] === RUN TestBuildInfo --- PASS: TestBuildInfo (0.00s) === RUN TestBasicTail ---- PASS: TestBasicTail (0.03s) +--- PASS: TestBasicTail (0.46s) === RUN TestNewLogDoesNotMatchIsIgnored ---- PASS: TestNewLogDoesNotMatchIsIgnored (0.01s) +--- PASS: TestNewLogDoesNotMatchIsIgnored (0.02s) === RUN TestBadProgramFailsCompilation ---- PASS: TestBadProgramFailsCompilation (0.00s) +--- PASS: TestBadProgramFailsCompilation (0.03s) === RUN TestExamplePrograms === RUN TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -2466,18 +2502,18 @@ === RUN TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog === RUN TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log === RUN TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log ---- PASS: TestExamplePrograms (22.03s) - --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.12s) - --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.09s) - --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (21.20s) - --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.08s) - --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.06s) - --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.04s) - --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.05s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.11s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.07s) - --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.05s) - --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.16s) +--- PASS: TestExamplePrograms (163.59s) + --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.76s) + --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.52s) + --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (159.53s) + --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.28s) + --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.18s) + --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.11s) + --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.22s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.62s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.43s) + --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.26s) + --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.68s) === RUN TestCompileExamplePrograms === RUN TestCompileExamplePrograms/apache_combined.mtail === RUN TestCompileExamplePrograms/apache_common.mtail @@ -2496,24 +2532,24 @@ === RUN TestCompileExamplePrograms/sftp.mtail === RUN TestCompileExamplePrograms/timer.mtail === RUN TestCompileExamplePrograms/vsftpd.mtail ---- PASS: TestCompileExamplePrograms (0.63s) - --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.04s) - --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.08s) - --- PASS: TestCompileExamplePrograms/histogram.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.04s) - --- PASS: TestCompileExamplePrograms/linecount.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.06s) +--- PASS: TestCompileExamplePrograms (2.57s) + --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.05s) + --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.08s) + --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.07s) + --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.31s) + --- PASS: TestCompileExamplePrograms/histogram.mtail (0.04s) + --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.12s) + --- PASS: TestCompileExamplePrograms/linecount.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.37s) --- PASS: TestCompileExamplePrograms/nocode.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/postfix.mtail (0.12s) - --- PASS: TestCompileExamplePrograms/rails.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/sftp.mtail (0.03s) - --- PASS: TestCompileExamplePrograms/timer.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.07s) + --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.13s) + --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.10s) + --- PASS: TestCompileExamplePrograms/postfix.mtail (0.45s) + --- PASS: TestCompileExamplePrograms/rails.mtail (0.05s) + --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.13s) + --- PASS: TestCompileExamplePrograms/sftp.mtail (0.18s) + --- PASS: TestCompileExamplePrograms/timer.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.41s) === RUN TestFilePipeStreamComparison === RUN TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -2526,18 +2562,18 @@ === RUN TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog === RUN TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log === RUN TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log ---- PASS: TestFilePipeStreamComparison (27.67s) - --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.11s) - --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.09s) - --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (27.14s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.02s) - --- PASS: TestFilePipeStreamComparison/examples/apache_common.mtail_on_testdata/apache-common.log (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.07s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.04s) - --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.08s) +--- PASS: TestFilePipeStreamComparison (175.61s) + --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.64s) + --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.38s) + --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (172.59s) + --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.19s) + --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.15s) + --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.09s) + --- PASS: TestFilePipeStreamComparison/examples/apache_common.mtail_on_testdata/apache-common.log (0.20s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.51s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.24s) + --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.16s) + --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.45s) === RUN TestFileSocketStreamComparison === RUN TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log === RUN TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log @@ -2561,91 +2597,91 @@ === RUN TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog === RUN TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log === RUN TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log ---- PASS: TestFileSocketStreamComparison (53.04s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.19s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.14s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (29.05s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.08s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.08s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.10s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.06s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (22.82s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unix://testdata/apache-combined.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unix://testdata/apache-common.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_log (0.07s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.08s) +--- PASS: TestFileSocketStreamComparison (380.33s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.50s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.29s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (192.28s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.28s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.21s) + --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.12s) + --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.34s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.68s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.42s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.35s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.78s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.86s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.67s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (180.50s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.22s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.12s) + --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unix://testdata/apache-combined.log (0.08s) + --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unix://testdata/apache-common.log (0.19s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_log (0.47s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.26s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.19s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.45s) === RUN TestExecMtail exec_integration_test.go:32: mtail binary not found, skipping exec_integration_test.go:33: --- SKIP: TestExecMtail (0.00s) === RUN TestLogDeletion ---- PASS: TestLogDeletion (0.02s) +--- PASS: TestLogDeletion (0.03s) === RUN TestGlobBeforeStart ---- PASS: TestGlobBeforeStart (0.01s) +--- PASS: TestGlobBeforeStart (0.03s) === RUN TestGlobAfterStart ---- PASS: TestGlobAfterStart (0.01s) +--- PASS: TestGlobAfterStart (0.03s) === RUN TestGlobIgnoreFolder ---- PASS: TestGlobIgnoreFolder (0.01s) +--- PASS: TestGlobIgnoreFolder (0.03s) === RUN TestFilenameRegexIgnore ---- PASS: TestFilenameRegexIgnore (0.01s) +--- PASS: TestFilenameRegexIgnore (0.04s) === RUN TestGlobRelativeAfterStart ---- PASS: TestGlobRelativeAfterStart (0.05s) +--- PASS: TestGlobRelativeAfterStart (0.09s) === RUN TestLogSoftLinkChange === RUN TestLogSoftLinkChange/race_simulation_disabled === RUN TestLogSoftLinkChange/race_simulation_enabled ---- PASS: TestLogSoftLinkChange (0.07s) - --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.03s) - --- PASS: TestLogSoftLinkChange/race_simulation_enabled (0.05s) +--- PASS: TestLogSoftLinkChange (0.21s) + --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.08s) + --- PASS: TestLogSoftLinkChange/race_simulation_enabled (0.12s) === RUN TestLogRotation === RUN TestLogRotation/race_simulation_disabled === RUN TestLogRotation/race_simulation_enabled ---- PASS: TestLogRotation (0.06s) - --- PASS: TestLogRotation/race_simulation_disabled (0.02s) - --- PASS: TestLogRotation/race_simulation_enabled (0.04s) +--- PASS: TestLogRotation (0.15s) + --- PASS: TestLogRotation/race_simulation_disabled (0.06s) + --- PASS: TestLogRotation/race_simulation_enabled (0.09s) === RUN TestLogTruncation ---- PASS: TestLogTruncation (0.03s) +--- PASS: TestLogTruncation (0.06s) === RUN TestPollLogPathPatterns ---- PASS: TestPollLogPathPatterns (0.03s) +--- PASS: TestPollLogPathPatterns (0.05s) === RUN TestMultipleLinesInOneWrite ---- PASS: TestMultipleLinesInOneWrite (0.03s) +--- PASS: TestMultipleLinesInOneWrite (0.05s) === RUN TestPartialLineRead ---- PASS: TestPartialLineRead (0.02s) +--- PASS: TestPartialLineRead (0.05s) === RUN TestPermissionDeniedOnLog ---- PASS: TestPermissionDeniedOnLog (0.01s) +--- PASS: TestPermissionDeniedOnLog (0.03s) === RUN TestNewProg ---- PASS: TestNewProg (0.01s) +--- PASS: TestNewProg (0.02s) === RUN TestProgramReloadNoDuplicateMetrics ---- PASS: TestProgramReloadNoDuplicateMetrics (0.05s) +--- PASS: TestProgramReloadNoDuplicateMetrics (0.10s) === RUN TestProgramUnloadIfDeleted ---- PASS: TestProgramUnloadIfDeleted (0.03s) +--- PASS: TestProgramUnloadIfDeleted (0.07s) === RUN TestReadFromPipe ---- PASS: TestReadFromPipe (0.01s) +--- PASS: TestReadFromPipe (0.02s) === RUN TestReadFromSocket === RUN TestReadFromSocket/unix === RUN TestReadFromSocket/unixgram ---- PASS: TestReadFromSocket (0.05s) - --- PASS: TestReadFromSocket/unix (0.02s) - --- PASS: TestReadFromSocket/unixgram (0.02s) +--- PASS: TestReadFromSocket (0.08s) + --- PASS: TestReadFromSocket/unix (0.04s) + --- PASS: TestReadFromSocket/unixgram (0.03s) === RUN TestRelativeLog ---- PASS: TestRelativeLog (0.02s) +--- PASS: TestRelativeLog (0.04s) === RUN TestBasicUNIXSockets ---- PASS: TestBasicUNIXSockets (0.00s) +--- PASS: TestBasicUNIXSockets (0.01s) PASS -ok github.com/google/mtail/internal/mtail 105.015s +ok github.com/google/mtail/internal/mtail 725.056s === RUN TestReadTestData ---- PASS: TestReadTestData (0.02s) +--- PASS: TestReadTestData (0.14s) PASS -ok github.com/google/mtail/internal/mtail/golden 1.043s +ok github.com/google/mtail/internal/mtail/golden 1.529s === RUN TestRuntimeEndToEnd === RUN TestRuntimeEndToEnd/single-dash-parseint === RUN TestRuntimeEndToEnd/histogram @@ -2669,59 +2705,59 @@ === RUN TestRuntimeEndToEnd/subst_integer === RUN TestRuntimeEndToEnd/regexp_replace === RUN TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr ---- PASS: TestRuntimeEndToEnd (0.70s) - --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.02s) - --- PASS: TestRuntimeEndToEnd/histogram (0.05s) - --- PASS: TestRuntimeEndToEnd/numbers (0.03s) - --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.03s) - --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.02s) - --- PASS: TestRuntimeEndToEnd/add_assign_float (0.02s) - --- PASS: TestRuntimeEndToEnd/decorator (0.05s) - --- PASS: TestRuntimeEndToEnd/else (0.03s) - --- PASS: TestRuntimeEndToEnd/otherwise (0.04s) - --- PASS: TestRuntimeEndToEnd/types (0.05s) - --- PASS: TestRuntimeEndToEnd/filename (0.02s) - --- PASS: TestRuntimeEndToEnd/logical_operators (0.04s) - --- PASS: TestRuntimeEndToEnd/strcat (0.02s) - --- PASS: TestRuntimeEndToEnd/typed-comparison (0.04s) - --- PASS: TestRuntimeEndToEnd/match-expression (0.04s) - --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.04s) - --- PASS: TestRuntimeEndToEnd/stringy (0.03s) - --- PASS: TestRuntimeEndToEnd/ip-addr (0.02s) - --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.02s) - --- PASS: TestRuntimeEndToEnd/subst_integer (0.03s) - --- PASS: TestRuntimeEndToEnd/regexp_replace (0.03s) - --- PASS: TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr (0.02s) +--- PASS: TestRuntimeEndToEnd (2.21s) + --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.04s) + --- PASS: TestRuntimeEndToEnd/histogram (0.17s) + --- PASS: TestRuntimeEndToEnd/numbers (0.14s) + --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.06s) + --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.08s) + --- PASS: TestRuntimeEndToEnd/add_assign_float (0.04s) + --- PASS: TestRuntimeEndToEnd/decorator (0.16s) + --- PASS: TestRuntimeEndToEnd/else (0.10s) + --- PASS: TestRuntimeEndToEnd/otherwise (0.15s) + --- PASS: TestRuntimeEndToEnd/types (0.27s) + --- PASS: TestRuntimeEndToEnd/filename (0.03s) + --- PASS: TestRuntimeEndToEnd/logical_operators (0.16s) + --- PASS: TestRuntimeEndToEnd/strcat (0.04s) + --- PASS: TestRuntimeEndToEnd/typed-comparison (0.11s) + --- PASS: TestRuntimeEndToEnd/match-expression (0.11s) + --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.12s) + --- PASS: TestRuntimeEndToEnd/stringy (0.08s) + --- PASS: TestRuntimeEndToEnd/ip-addr (0.05s) + --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.06s) + --- PASS: TestRuntimeEndToEnd/subst_integer (0.07s) + --- PASS: TestRuntimeEndToEnd/regexp_replace (0.07s) + --- PASS: TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr (0.08s) === RUN TestNewRuntime --- PASS: TestNewRuntime (0.00s) === RUN TestNewRuntimeErrors --- PASS: TestNewRuntimeErrors (0.00s) === RUN TestCompileAndRun ---- PASS: TestCompileAndRun (0.00s) +--- PASS: TestCompileAndRun (0.01s) === RUN TestLoadProg ---- PASS: TestLoadProg (0.00s) +--- PASS: TestLoadProg (0.02s) PASS -ok github.com/google/mtail/internal/runtime 1.739s +ok github.com/google/mtail/internal/runtime 3.632s === RUN TestOpcodeHasString --- PASS: TestOpcodeHasString (0.00s) === RUN TestInstrString --- PASS: TestInstrString (0.00s) PASS -ok github.com/google/mtail/internal/runtime/code 1.026s +ok github.com/google/mtail/internal/runtime/code 1.140s === RUN TestCompileParserError --- PASS: TestCompileParserError (0.00s) === RUN TestCompileCheckerError ---- PASS: TestCompileCheckerError (0.00s) +--- PASS: TestCompileCheckerError (0.01s) === RUN TestCompileCodegen ---- PASS: TestCompileCodegen (0.00s) +--- PASS: TestCompileCodegen (0.01s) PASS -ok github.com/google/mtail/internal/runtime/compiler 1.036s +ok github.com/google/mtail/internal/runtime/compiler 1.388s === RUN TestWalkPanicsOnUnknown --- PASS: TestWalkPanicsOnUnknown (0.00s) === RUN TestAstReplacement --- PASS: TestAstReplacement (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/ast 1.030s +ok github.com/google/mtail/internal/runtime/compiler/ast 1.368s === RUN TestCheckInvalidPrograms === RUN TestCheckInvalidPrograms/undefined_named_capture_group === RUN TestCheckInvalidPrograms/out_of_bounds_capref @@ -2758,15 +2794,15 @@ === RUN TestCheckInvalidPrograms/dec_non_var === RUN TestCheckInvalidPrograms/cmp_to_None === RUN TestCheckInvalidPrograms/negate_None ---- PASS: TestCheckInvalidPrograms (0.04s) +--- PASS: TestCheckInvalidPrograms (0.18s) --- PASS: TestCheckInvalidPrograms/undefined_named_capture_group (0.00s) - --- PASS: TestCheckInvalidPrograms/out_of_bounds_capref (0.00s) - --- PASS: TestCheckInvalidPrograms/undefined_decorator (0.00s) - --- PASS: TestCheckInvalidPrograms/undefined_identifier (0.00s) + --- PASS: TestCheckInvalidPrograms/out_of_bounds_capref (0.09s) + --- PASS: TestCheckInvalidPrograms/undefined_decorator (0.01s) + --- PASS: TestCheckInvalidPrograms/undefined_identifier (0.01s) --- PASS: TestCheckInvalidPrograms/invalid_regex_1 (0.00s) --- PASS: TestCheckInvalidPrograms/invalid_regex_2 (0.00s) --- PASS: TestCheckInvalidPrograms/invalid_regex_3 (0.00s) - --- PASS: TestCheckInvalidPrograms/duplicate_declaration (0.00s) + --- PASS: TestCheckInvalidPrograms/duplicate_declaration (0.01s) --- PASS: TestCheckInvalidPrograms/indexedExpr_parameter_count (0.00s) --- PASS: TestCheckInvalidPrograms/indexedExpr_binary_expression (0.00s) --- PASS: TestCheckInvalidPrograms/builtin_parameter_mismatch (0.00s) @@ -2791,7 +2827,7 @@ --- PASS: TestCheckInvalidPrograms/mod_by_zero (0.00s) --- PASS: TestCheckInvalidPrograms/assign_to_rvalue (0.00s) --- PASS: TestCheckInvalidPrograms/tolower_non_string (0.00s) - --- PASS: TestCheckInvalidPrograms/dec_non_var (0.00s) + --- PASS: TestCheckInvalidPrograms/dec_non_var (0.01s) --- PASS: TestCheckInvalidPrograms/cmp_to_None (0.00s) --- PASS: TestCheckInvalidPrograms/negate_None (0.00s) === RUN TestCheckValidPrograms @@ -2827,7 +2863,7 @@ === RUN TestCheckValidPrograms/negative_numbers_in_capture_groups === RUN TestCheckValidPrograms/substitution === RUN TestCheckValidPrograms/regexp_subst ---- PASS: TestCheckValidPrograms (0.02s) +--- PASS: TestCheckValidPrograms (0.03s) --- PASS: TestCheckValidPrograms/capture_group (0.00s) --- PASS: TestCheckValidPrograms/shadowed_positionals (0.00s) --- PASS: TestCheckValidPrograms/sibling_positionals (0.00s) @@ -2869,7 +2905,7 @@ --- PASS: TestCheckTypeExpressions/Int_+_Float_->_Float (0.00s) --- PASS: TestCheckTypeExpressions/⍺_+_Float_->_Float (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/checker 1.081s +ok github.com/google/mtail/internal/runtime/compiler/checker 1.580s === RUN TestCodeGenFromSource === RUN TestCodeGenFromSource/simple_line_counter === RUN TestCodeGenFromSource/count_a @@ -2922,12 +2958,12 @@ === RUN TestCodeGenFromSource/negative_numbers_in_capture_groups === RUN TestCodeGenFromSource/substitution === RUN TestCodeGenFromSource/const_term_as_pattern ---- PASS: TestCodeGenFromSource (0.06s) +--- PASS: TestCodeGenFromSource (0.34s) --- PASS: TestCodeGenFromSource/simple_line_counter (0.00s) --- PASS: TestCodeGenFromSource/count_a (0.00s) --- PASS: TestCodeGenFromSource/strptime_and_capref (0.00s) --- PASS: TestCodeGenFromSource/strptime_and_named_capref (0.00s) - --- PASS: TestCodeGenFromSource/inc_by_and_set (0.00s) + --- PASS: TestCodeGenFromSource/inc_by_and_set (0.01s) --- PASS: TestCodeGenFromSource/cond_expr_gt (0.00s) --- PASS: TestCodeGenFromSource/cond_expr_lt (0.00s) --- PASS: TestCodeGenFromSource/cond_expr_eq (0.00s) @@ -2945,28 +2981,28 @@ --- PASS: TestCodeGenFromSource/otherwise (0.00s) --- PASS: TestCodeGenFromSource/cond_else (0.00s) --- PASS: TestCodeGenFromSource/mod (0.00s) - --- PASS: TestCodeGenFromSource/del (0.00s) - --- PASS: TestCodeGenFromSource/del_after (0.00s) - --- PASS: TestCodeGenFromSource/types (0.00s) + --- PASS: TestCodeGenFromSource/del (0.15s) + --- PASS: TestCodeGenFromSource/del_after (0.01s) + --- PASS: TestCodeGenFromSource/types (0.01s) --- PASS: TestCodeGenFromSource/getfilename (0.00s) --- PASS: TestCodeGenFromSource/dimensioned_counter (0.00s) - --- PASS: TestCodeGenFromSource/string_to_int (0.00s) + --- PASS: TestCodeGenFromSource/string_to_int (0.01s) --- PASS: TestCodeGenFromSource/int_to_float (0.00s) --- PASS: TestCodeGenFromSource/string_to_float (0.00s) --- PASS: TestCodeGenFromSource/float_to_string (0.00s) - --- PASS: TestCodeGenFromSource/int_to_string (0.00s) - --- PASS: TestCodeGenFromSource/nested_comparisons (0.00s) + --- PASS: TestCodeGenFromSource/int_to_string (0.01s) + --- PASS: TestCodeGenFromSource/nested_comparisons (0.01s) --- PASS: TestCodeGenFromSource/string_concat (0.00s) --- PASS: TestCodeGenFromSource/add_assign_float (0.00s) --- PASS: TestCodeGenFromSource/match_expression (0.00s) --- PASS: TestCodeGenFromSource/negative_match_expression (0.00s) --- PASS: TestCodeGenFromSource/capref_used_in_def (0.00s) --- PASS: TestCodeGenFromSource/binop_arith_type_conversion (0.00s) - --- PASS: TestCodeGenFromSource/binop_compare_type_conversion (0.00s) + --- PASS: TestCodeGenFromSource/binop_compare_type_conversion (0.01s) --- PASS: TestCodeGenFromSource/set_string (0.00s) --- PASS: TestCodeGenFromSource/concat_to_text (0.00s) --- PASS: TestCodeGenFromSource/decrement (0.00s) - --- PASS: TestCodeGenFromSource/capref_and_settime (0.00s) + --- PASS: TestCodeGenFromSource/capref_and_settime (0.01s) --- PASS: TestCodeGenFromSource/cast_to_self (0.00s) --- PASS: TestCodeGenFromSource/stop (0.00s) --- PASS: TestCodeGenFromSource/stop_inside (0.00s) @@ -2981,11 +3017,11 @@ --- PASS: TestCodeGenFromAST/subst (0.00s) --- PASS: TestCodeGenFromAST/regexp_subst (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/codegen 1.096s +ok github.com/google/mtail/internal/runtime/compiler/codegen 1.488s === RUN TestNilErrorPosition --- PASS: TestNilErrorPosition (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/errors 1.020s +ok github.com/google/mtail/internal/runtime/compiler/errors 1.355s === RUN TestOptimiser === RUN TestOptimiser/int_add === RUN TestOptimiser/float_mul @@ -3003,45 +3039,45 @@ === RUN TestOptimiserErrors/float_divide_by_zero === RUN TestOptimiserErrors/integer_mod_by_zero === RUN TestOptimiserErrors/float_mod_by_zero ---- PASS: TestOptimiserErrors (0.00s) +--- PASS: TestOptimiserErrors (0.01s) --- PASS: TestOptimiserErrors/integer_divide_by_zero (0.00s) --- PASS: TestOptimiserErrors/float_divide_by_zero (0.00s) - --- PASS: TestOptimiserErrors/integer_mod_by_zero (0.00s) + --- PASS: TestOptimiserErrors/integer_mod_by_zero (0.01s) --- PASS: TestOptimiserErrors/float_mod_by_zero (0.00s) === RUN TestConstFoldQuickIntComm === RUN TestConstFoldQuickIntComm/add === RUN TestConstFoldQuickIntComm/mul ---- PASS: TestConstFoldQuickIntComm (0.01s) - --- PASS: TestConstFoldQuickIntComm/add (0.00s) - --- PASS: TestConstFoldQuickIntComm/mul (0.01s) +--- PASS: TestConstFoldQuickIntComm (0.06s) + --- PASS: TestConstFoldQuickIntComm/add (0.03s) + --- PASS: TestConstFoldQuickIntComm/mul (0.03s) === RUN TestConstFoldQuickFloatComm === RUN TestConstFoldQuickFloatComm/add === RUN TestConstFoldQuickFloatComm/mul ---- PASS: TestConstFoldQuickFloatComm (0.01s) - --- PASS: TestConstFoldQuickFloatComm/add (0.01s) - --- PASS: TestConstFoldQuickFloatComm/mul (0.00s) +--- PASS: TestConstFoldQuickFloatComm (0.04s) + --- PASS: TestConstFoldQuickFloatComm/add (0.02s) + --- PASS: TestConstFoldQuickFloatComm/mul (0.02s) === RUN TestConstFoldQuickMixedComm -=== RUN TestConstFoldQuickMixedComm/mul === RUN TestConstFoldQuickMixedComm/add ---- PASS: TestConstFoldQuickMixedComm (0.01s) - --- PASS: TestConstFoldQuickMixedComm/mul (0.00s) - --- PASS: TestConstFoldQuickMixedComm/add (0.01s) +=== RUN TestConstFoldQuickMixedComm/mul +--- PASS: TestConstFoldQuickMixedComm (0.04s) + --- PASS: TestConstFoldQuickMixedComm/add (0.02s) + --- PASS: TestConstFoldQuickMixedComm/mul (0.02s) === RUN TestConstFoldQuickIntAddSub ---- PASS: TestConstFoldQuickIntAddSub (0.01s) +--- PASS: TestConstFoldQuickIntAddSub (0.02s) === RUN TestConstFoldQuickFloatAddSub ---- PASS: TestConstFoldQuickFloatAddSub (0.01s) +--- PASS: TestConstFoldQuickFloatAddSub (0.08s) === RUN TestConstFoldQuickMixedAddSub ---- PASS: TestConstFoldQuickMixedAddSub (0.00s) +--- PASS: TestConstFoldQuickMixedAddSub (0.02s) === RUN TestConstFoldQuickFloatMulDiv ---- PASS: TestConstFoldQuickFloatMulDiv (0.01s) +--- PASS: TestConstFoldQuickFloatMulDiv (0.04s) === RUN TestConstFoldQuickIntModAddition ---- PASS: TestConstFoldQuickIntModAddition (0.01s) +--- PASS: TestConstFoldQuickIntModAddition (0.02s) === RUN TestConstFoldQuickFloatModAddition ---- PASS: TestConstFoldQuickFloatModAddition (0.01s) +--- PASS: TestConstFoldQuickFloatModAddition (0.02s) === RUN TestConstFoldQuickMixedPowProduct ---- PASS: TestConstFoldQuickMixedPowProduct (0.01s) +--- PASS: TestConstFoldQuickMixedPowProduct (0.02s) PASS -ok github.com/google/mtail/internal/runtime/compiler/opt 1.121s +ok github.com/google/mtail/internal/runtime/compiler/opt 1.752s === RUN TestLex === RUN TestLex/empty === RUN TestLex/spaces @@ -3068,17 +3104,17 @@ === RUN TestLex/unexpected_char === RUN TestLex/unterminated_regex === RUN TestLex/unterminated_quoted_string ---- PASS: TestLex (0.02s) +--- PASS: TestLex (0.07s) --- PASS: TestLex/empty (0.00s) --- PASS: TestLex/spaces (0.00s) --- PASS: TestLex/newlines (0.00s) --- PASS: TestLex/comment (0.00s) --- PASS: TestLex/comment_not_at_col_1 (0.00s) - --- PASS: TestLex/punctuation (0.00s) - --- PASS: TestLex/operators (0.00s) + --- PASS: TestLex/punctuation (0.01s) + --- PASS: TestLex/operators (0.01s) --- PASS: TestLex/keywords (0.00s) - --- PASS: TestLex/builtins (0.00s) - --- PASS: TestLex/numbers (0.00s) + --- PASS: TestLex/builtins (0.01s) + --- PASS: TestLex/numbers (0.01s) --- PASS: TestLex/identifier (0.00s) --- PASS: TestLex/regex (0.00s) --- PASS: TestLex/regex_with_escape (0.00s) @@ -3158,11 +3194,11 @@ === RUN TestParserRoundTrip/stop === RUN TestParserRoundTrip/substitution === RUN TestParserRoundTrip/pattern_in_arg_expr_list ---- PASS: TestParserRoundTrip (0.04s) +--- PASS: TestParserRoundTrip (0.11s) --- PASS: TestParserRoundTrip/empty (0.00s) --- PASS: TestParserRoundTrip/newline (0.00s) --- PASS: TestParserRoundTrip/declare_counter (0.00s) - --- PASS: TestParserRoundTrip/declare_counter_string_name (0.00s) + --- PASS: TestParserRoundTrip/declare_counter_string_name (0.01s) --- PASS: TestParserRoundTrip/declare_dimensioned_counter (0.00s) --- PASS: TestParserRoundTrip/declare_dimensioned_metric_with_limit (0.00s) --- PASS: TestParserRoundTrip/declare_multi-dimensioned_counter (0.00s) @@ -3171,7 +3207,7 @@ --- PASS: TestParserRoundTrip/declare_timer (0.00s) --- PASS: TestParserRoundTrip/declare_text (0.00s) --- PASS: TestParserRoundTrip/declare_histogram (0.00s) - --- PASS: TestParserRoundTrip/declare_histogram_float (0.00s) + --- PASS: TestParserRoundTrip/declare_histogram_float (0.01s) --- PASS: TestParserRoundTrip/declare_histogram_by_ (0.00s) --- PASS: TestParserRoundTrip/declare_histogram_reversed_syntax_ (0.00s) --- PASS: TestParserRoundTrip/simple_pattern_action (0.00s) @@ -3181,10 +3217,10 @@ --- PASS: TestParserRoundTrip/numeric_capture_group_reference (0.00s) --- PASS: TestParserRoundTrip/strptime_and_capref (0.00s) --- PASS: TestParserRoundTrip/named_capture_group_reference (0.00s) - --- PASS: TestParserRoundTrip/nested_match_conditions (0.00s) + --- PASS: TestParserRoundTrip/nested_match_conditions (0.01s) --- PASS: TestParserRoundTrip/nested_scope (0.00s) --- PASS: TestParserRoundTrip/comment_then_code (0.00s) - --- PASS: TestParserRoundTrip/assignment (0.00s) + --- PASS: TestParserRoundTrip/assignment (0.01s) --- PASS: TestParserRoundTrip/increment_operator (0.00s) --- PASS: TestParserRoundTrip/incby_operator (0.00s) --- PASS: TestParserRoundTrip/additive (0.00s) @@ -3265,17 +3301,17 @@ === RUN TestKindHasString --- PASS: TestKindHasString (0.00s) === RUN TestTokenString ---- PASS: TestTokenString (0.02s) +--- PASS: TestTokenString (0.07s) PASS -ok github.com/google/mtail/internal/runtime/compiler/parser 1.121s +ok github.com/google/mtail/internal/runtime/compiler/parser 1.612s === RUN TestInsertLookup --- PASS: TestInsertLookup (0.00s) === RUN TestInsertLookupQuick ---- PASS: TestInsertLookupQuick (7.23s) +--- PASS: TestInsertLookupQuick (36.27s) === RUN TestNestedScope --- PASS: TestNestedScope (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/symbol 8.346s +ok github.com/google/mtail/internal/runtime/compiler/symbol 37.611s === RUN TestTypeUnification === RUN TestTypeUnification/None_None === RUN TestTypeUnification/String_String @@ -3369,7 +3405,7 @@ === RUN TestInferCaprefType/-|[0-9] === RUN TestInferCaprefType/\d+\.\d+|\- === RUN TestInferCaprefType/\-|\d+\.\d+ ---- PASS: TestInferCaprefType (0.00s) +--- PASS: TestInferCaprefType (0.01s) --- PASS: TestInferCaprefType/\d+ (0.00s) --- PASS: TestInferCaprefType/-?\d+ (0.00s) --- PASS: TestInferCaprefType/[-+]?[0-9]*\.?[0-9]+([eE][-+]?[0-9]+)? (0.00s) @@ -3387,7 +3423,7 @@ === RUN TestAsTypeError --- PASS: TestAsTypeError (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/types 1.044s +ok github.com/google/mtail/internal/runtime/compiler/types 1.153s === RUN TestInstrs === RUN TestInstrs/match === RUN TestInstrs/cmp_lt @@ -3451,7 +3487,7 @@ === RUN TestInstrs/fcmp_gt_false === RUN TestInstrs/scmp_eq_false === RUN TestInstrs/subst ---- PASS: TestInstrs (0.03s) +--- PASS: TestInstrs (0.05s) --- PASS: TestInstrs/match (0.00s) --- PASS: TestInstrs/cmp_lt (0.00s) --- PASS: TestInstrs/cmp_eq (0.00s) @@ -3466,7 +3502,7 @@ --- PASS: TestInstrs/cmp_eq_string_string_true (0.00s) --- PASS: TestInstrs/cmp_gt_float_float#01 (0.00s) --- PASS: TestInstrs/cmp_gt_float_int#01 (0.00s) - --- PASS: TestInstrs/cmp_gt_int_float#01 (0.00s) + --- PASS: TestInstrs/cmp_gt_int_float#01 (0.01s) --- PASS: TestInstrs/jnm (0.00s) --- PASS: TestInstrs/jm (0.00s) --- PASS: TestInstrs/jmp (0.00s) @@ -3547,46 +3583,46 @@ === RUN TestTimestampInstr --- PASS: TestTimestampInstr (0.00s) PASS -ok github.com/google/mtail/internal/runtime/vm 1.073s +ok github.com/google/mtail/internal/runtime/vm 1.416s === RUN TestTail --- PASS: TestTail (0.00s) === RUN TestTailErrors --- PASS: TestTailErrors (0.00s) === RUN TestHandleLogUpdate ---- PASS: TestHandleLogUpdate (0.01s) +--- PASS: TestHandleLogUpdate (0.22s) === RUN TestHandleLogTruncate ---- PASS: TestHandleLogTruncate (0.01s) +--- PASS: TestHandleLogTruncate (0.03s) === RUN TestHandleLogUpdatePartialLine ---- PASS: TestHandleLogUpdatePartialLine (0.01s) +--- PASS: TestHandleLogUpdatePartialLine (0.06s) === RUN TestTailerUnreadableFile ---- PASS: TestTailerUnreadableFile (0.00s) +--- PASS: TestTailerUnreadableFile (0.01s) === RUN TestTailerInitErrors --- PASS: TestTailerInitErrors (0.00s) === RUN TestTailExpireStaleHandles tail_test.go:227: need to set lastRead on logstream to inject condition --- SKIP: TestTailExpireStaleHandles (0.00s) === RUN TestTailerOpenRetries ---- PASS: TestTailerOpenRetries (0.01s) +--- PASS: TestTailerOpenRetries (0.02s) PASS -ok github.com/google/mtail/internal/tailer 1.073s +ok github.com/google/mtail/internal/tailer 1.680s === RUN TestNewErrors --- PASS: TestNewErrors (0.00s) === RUN TestDgramStreamReadCompletedBecauseSocketClosed === RUN TestDgramStreamReadCompletedBecauseSocketClosed/unixgram === RUN TestDgramStreamReadCompletedBecauseSocketClosed/udp ---- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.01s) - --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.00s) +--- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.09s) + --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.09s) --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/udp (0.00s) === RUN TestDgramStreamReadCompletedBecauseCancel === RUN TestDgramStreamReadCompletedBecauseCancel/unixgram === RUN TestDgramStreamReadCompletedBecauseCancel/udp --- PASS: TestDgramStreamReadCompletedBecauseCancel (0.01s) --- PASS: TestDgramStreamReadCompletedBecauseCancel/unixgram (0.00s) - --- PASS: TestDgramStreamReadCompletedBecauseCancel/udp (0.00s) + --- PASS: TestDgramStreamReadCompletedBecauseCancel/udp (0.01s) === RUN TestFileStreamRead ---- PASS: TestFileStreamRead (0.01s) +--- PASS: TestFileStreamRead (0.13s) === RUN TestFileStreamReadNonSingleByteEnd ---- PASS: TestFileStreamReadNonSingleByteEnd (0.02s) +--- PASS: TestFileStreamReadNonSingleByteEnd (0.16s) === RUN TestStreamDoesntBreakOnCorruptRune --- PASS: TestStreamDoesntBreakOnCorruptRune (0.01s) === RUN TestFileStreamTruncation @@ -3594,11 +3630,11 @@ === RUN TestFileStreamFinishedBecauseCancel --- PASS: TestFileStreamFinishedBecauseCancel (0.01s) === RUN TestFileStreamPartialRead ---- PASS: TestFileStreamPartialRead (0.01s) +--- PASS: TestFileStreamPartialRead (0.03s) === RUN TestFileStreamRotation ---- PASS: TestFileStreamRotation (0.01s) +--- PASS: TestFileStreamRotation (0.02s) === RUN TestFileStreamURL ---- PASS: TestFileStreamURL (0.00s) +--- PASS: TestFileStreamURL (0.01s) === RUN TestFileStreamOpenFailure --- PASS: TestFileStreamOpenFailure (0.00s) === RUN TestPipeStreamReadCompletedBecauseClosed @@ -3610,17 +3646,17 @@ === RUN TestSocketStreamReadCompletedBecauseSocketClosed === RUN TestSocketStreamReadCompletedBecauseSocketClosed/unix === RUN TestSocketStreamReadCompletedBecauseSocketClosed/tcp ---- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.00s) - --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/unix (0.00s) +--- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.02s) + --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/unix (0.01s) --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/tcp (0.00s) === RUN TestSocketStreamReadCompletedBecauseCancel === RUN TestSocketStreamReadCompletedBecauseCancel/unix === RUN TestSocketStreamReadCompletedBecauseCancel/tcp ---- PASS: TestSocketStreamReadCompletedBecauseCancel (0.00s) +--- PASS: TestSocketStreamReadCompletedBecauseCancel (0.03s) --- PASS: TestSocketStreamReadCompletedBecauseCancel/unix (0.00s) - --- PASS: TestSocketStreamReadCompletedBecauseCancel/tcp (0.00s) + --- PASS: TestSocketStreamReadCompletedBecauseCancel/tcp (0.02s) PASS -ok github.com/google/mtail/internal/tailer/logstream 1.137s +ok github.com/google/mtail/internal/tailer/logstream 1.862s === RUN TestDoOrTimeoutNeverOK --- PASS: TestDoOrTimeoutNeverOK (0.01s) === RUN TestDoOrTimeoutAlwaysOK @@ -3628,11 +3664,11 @@ === RUN TestDoOrTimeoutStallThenOK --- PASS: TestDoOrTimeoutStallThenOK (0.05s) === RUN TestDoOrTimeoutAlwaysErr ---- PASS: TestDoOrTimeoutAlwaysErr (0.00s) +--- PASS: TestDoOrTimeoutAlwaysErr (0.01s) PASS -ok github.com/google/mtail/internal/testutil 1.091s +ok github.com/google/mtail/internal/testutil 1.392s === RUN TestTestWakerWakes ---- PASS: TestTestWakerWakes (0.00s) +--- PASS: TestTestWakerWakes (0.09s) === RUN TestTestWakerTwoWakees --- PASS: TestTestWakerTwoWakees (0.00s) === RUN TestTestWakerTwoWakeups @@ -3640,7 +3676,7 @@ === RUN TestTimedWakerWakes --- PASS: TestTimedWakerWakes (0.01s) PASS -ok github.com/google/mtail/internal/waker 1.032s +ok github.com/google/mtail/internal/waker 1.409s make[1]: Leaving directory '/build/reproducible-path/mtail-3.0.0~rc54+git0ff5' create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang -O--builddirectory=/build/reproducible-path/mtail-3.0.0\~rc54\+git0ff5/build @@ -3692,12 +3728,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/3342912/tmp/hooks/B01_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/1022324 and its subdirectories -I: Current time: Thu May 29 11:28:20 -12 2025 -I: pbuilder-time-stamp: 1748561300 +I: removing directory /srv/workspace/pbuilder/3342912 and its subdirectories +I: Current time: Sat Apr 27 07:29:05 +14 2024 +I: pbuilder-time-stamp: 1714152545