Diff of the two buildlogs: -- --- b1/build.log 2024-06-02 00:35:45.961310426 +0000 +++ b2/build.log 2024-06-02 00:40:08.586138367 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sat Jun 1 12:26:10 -12 2024 -I: pbuilder-time-stamp: 1717287970 +I: Current time: Sat Jul 5 20:58:48 +14 2025 +I: pbuilder-time-stamp: 1751698728 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-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/1089091/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/40421/tmp/hooks/D01_modify_environment starting +debug: Running on ionos5-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jul 5 06:58 /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/40421/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/40421/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=20 ' - DISTRIBUTION='unstable' - 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=42 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='d0d610c5e28d48fbbc7cc0ffbdc45851' - 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='1089091' - PS1='# ' - PS2='> ' + INVOCATION_ID=72d47531f65c475fa3d7f1198910d8cd + 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=40421 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.3nvS6vNy/pbuilderrc_UqOK --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3nvS6vNy/b1 --logfile b1/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+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132: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.3nvS6vNy/pbuilderrc_FnTY --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3nvS6vNy/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://213.165.73.152:3128 I: uname -a - Linux ionos1-amd64 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.7.12+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.7.12-1~bpo12+1 (2024-05-06) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jun 1 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1089091/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jul 4 14:06 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/40421/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -215,7 +247,7 @@ Get: 80 http://deb.debian.org/debian unstable/main amd64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [326 kB] Get: 81 http://deb.debian.org/debian unstable/main amd64 libjs-jquery-ui all 1.13.2+dfsg-1 [250 kB] Get: 82 http://deb.debian.org/debian unstable/main amd64 golang-golang-x-tools amd64 1:0.20.0+ds-1 [39.8 MB] -Fetched 130 MB in 3s (50.5 MB/s) +Fetched 130 MB in 4s (35.2 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 ... 19719 files and directories currently installed.) @@ -489,8 +521,8 @@ Setting up tzdata (2024a-4) ... Current default time zone: 'Etc/UTC' -Local time is now: Sun Jun 2 00:27:29 UTC 2024. -Universal Time is now: Sun Jun 2 00:27:29 UTC 2024. +Local time is now: Sat Jul 5 06:59:32 UTC 2025. +Universal Time is now: Sat Jul 5 06:59:32 UTC 2025. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up golang-github-cespare-xxhash-dev (2.1.1-2) ... @@ -564,7 +596,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/40421/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/40421/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 @@ -717,269 +753,269 @@ 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 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 -internal/coverage/rtcov + 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 +encoding +internal/race internal/godebugs +cmp +vendor/golang.org/x/crypto/cryptobyte/asn1 +internal/itoa +google.golang.org/protobuf/internal/flags +go.opencensus.io/trace/internal +crypto/internal/alias +internal/coverage/rtcov +go.opencensus.io/internal/tagencoding +go.opencensus.io unicode/utf8 -encoding -math/bits -internal/unsafeheader +github.com/google/go-cmp/cmp/internal/flags +unicode/utf16 internal/goarch runtime/internal/atomic -unicode/utf16 -internal/goos +internal/unsafeheader +vendor/golang.org/x/crypto/internal/alias +container/list +log/internal +crypto/internal/boring/sig runtime/internal/syscall +math/bits internal/cpu -crypto/internal/alias -unicode -cmp -log/internal -container/list -internal/goexperiment crypto/subtle -internal/itoa -internal/race sync/atomic -internal/chacha8rand -internal/abi +internal/goexperiment +internal/goos +unicode +internal/nettrace runtime/internal/math +internal/abi +internal/chacha8rand runtime/internal/sys -crypto/internal/boring/sig -vendor/golang.org/x/crypto/cryptobyte/asn1 -github.com/google/go-cmp/cmp/internal/flags -go.opencensus.io -internal/nettrace -vendor/golang.org/x/crypto/internal/alias -go.opencensus.io/internal/tagencoding -google.golang.org/protobuf/internal/flags -slices -google.golang.org/protobuf/internal/set github.com/golang/groupcache/lru -go.opencensus.io/trace/internal +google.golang.org/protobuf/internal/set +slices internal/bytealg math runtime internal/reflectlite -sync runtime/metrics +sync internal/testlog -internal/bisect internal/singleflight +internal/bisect google.golang.org/protobuf/internal/pragma runtime/cgo +internal/godebug errors sort -internal/godebug -strconv -io internal/oserror internal/safefilepath path +internal/intern +vendor/golang.org/x/net/dns/dnsmessage math/rand +io +strconv crypto/internal/nistec/fiat -vendor/golang.org/x/net/dns/dnsmessage -internal/intern syscall github.com/beorn7/perks/quantile -strings -bytes hash -text/tabwriter crypto/internal/randutil -hash/crc32 +text/tabwriter +bytes +strings hash/fnv -reflect -crypto -crypto/rc4 -net/netip +hash/crc32 github.com/golang/glog/internal/stackdump vendor/golang.org/x/text/transform -bufio +crypto/rc4 +crypto +net/netip +reflect net/http/internal/ascii -html -regexp/syntax +bufio github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg -internal/syscall/unix +regexp/syntax +html internal/syscall/execenv +internal/syscall/unix time regexp -io/fs +context github.com/google/go-cmp/cmp/internal/diff +io/fs internal/poll -context github.com/google/mtail/internal/logline embed -internal/fmtsort -encoding/binary -github.com/google/go-cmp/cmp/internal/function google.golang.org/protobuf/internal/editiondefaults os +github.com/google/go-cmp/cmp/internal/function +internal/fmtsort +encoding/binary +internal/lazyregexp +internal/sysinfo +io/ioutil +path/filepath +github.com/prometheus/procfs/internal/util +os/signal +fmt +vendor/golang.org/x/sys/cpu encoding/base64 -crypto/cipher -crypto/internal/edwards25519/field crypto/md5 vendor/golang.org/x/crypto/internal/poly1305 +crypto/cipher github.com/cespare/xxhash +google.golang.org/protobuf/internal/detrand +crypto/internal/edwards25519/field crypto/internal/nistec golang.org/x/sys/unix encoding/pem -crypto/internal/edwards25519 -crypto/internal/boring -crypto/des vendor/golang.org/x/crypto/chacha20 -crypto/sha512 +crypto/des +crypto/internal/boring crypto/hmac -crypto/sha1 +crypto/sha512 crypto/aes +crypto/sha1 crypto/sha256 vendor/golang.org/x/crypto/hkdf -fmt -path/filepath -google.golang.org/protobuf/internal/detrand -io/ioutil -github.com/prometheus/procfs/internal/util -os/signal -vendor/golang.org/x/sys/cpu -internal/sysinfo -internal/lazyregexp -net -crypto/ecdh -vendor/golang.org/x/crypto/chacha20poly1305 github.com/golang/glog/internal/logsink -flag log -compress/flate -os/user -mime/quotedprintable -net/http/internal -github.com/google/mtail/internal/runtime/compiler/position -vendor/golang.org/x/net/http2/hpack github.com/pkg/errors encoding/hex -net/url +compress/flate encoding/json -google.golang.org/protobuf/internal/errors -vendor/golang.org/x/text/unicode/norm +net/url math/big -mime -go/token google.golang.org/protobuf/internal/version -github.com/prometheus/procfs/internal/fs -google.golang.org/protobuf/encoding/protowire -runtime/debug -text/template/parse github.com/google/go-cmp/cmp/internal/value -runtime/trace -database/sql/driver -vendor/golang.org/x/text/unicode/bidi -github.com/google/mtail/internal/runtime/compiler/errors -google.golang.org/protobuf/reflect/protoreflect -go.opencensus.io/internal -go.opencensus.io/resource +net/http/internal +text/template/parse +flag +github.com/google/mtail/internal/runtime/compiler/position +mime go.opencensus.io/trace/tracestate +runtime/trace google.golang.org/grpc/codes +mime/quotedprintable +github.com/prometheus/procfs/internal/fs +go.opencensus.io/resource +google.golang.org/protobuf/internal/errors google.golang.org/grpc/internal/grpclog +database/sql/driver +vendor/golang.org/x/net/http2/hpack +vendor/golang.org/x/text/unicode/norm +go.opencensus.io/internal +go/token +runtime/debug google.golang.org/grpc/metadata +google.golang.org/protobuf/encoding/protowire go.opencensus.io/metric/metricdata +github.com/google/mtail/internal/runtime/compiler/errors +go.opencensus.io/metric/metricproducer +vendor/golang.org/x/text/unicode/bidi google.golang.org/grpc/grpclog -compress/gzip +google.golang.org/protobuf/reflect/protoreflect github.com/google/go-cmp/cmp -testing database/sql -go.opencensus.io/metric/metricproducer +compress/gzip +testing +vendor/golang.org/x/crypto/chacha20poly1305 +crypto/internal/edwards25519 +crypto/ecdh vendor/golang.org/x/text/secure/bidirule +github.com/google/mtail/internal/metrics/datum +github.com/golang/protobuf/proto +text/template runtime/pprof internal/profile -google.golang.org/protobuf/internal/descfmt +vendor/golang.org/x/net/idna google.golang.org/protobuf/internal/descopts -google.golang.org/protobuf/internal/genid -google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs -google.golang.org/protobuf/internal/order +google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/runtime/protoiface -vendor/golang.org/x/net/idna -text/template -google.golang.org/protobuf/reflect/protoregistry -google.golang.org/protobuf/internal/encoding/text +google.golang.org/protobuf/internal/order +google.golang.org/protobuf/internal/genid +google.golang.org/protobuf/internal/descfmt crypto/rand -crypto/elliptic crypto/internal/boring/bbig +crypto/elliptic +crypto/dsa crypto/internal/bigmod encoding/asn1 -crypto/dsa -github.com/google/mtail/internal/metrics/datum -github.com/golang/protobuf/proto -go.opencensus.io/tag -google.golang.org/protobuf/proto +github.com/google/go-cmp/cmp/cmpopts +os/user +net +google.golang.org/protobuf/reflect/protoregistry +google.golang.org/protobuf/internal/encoding/text crypto/ed25519 go.opencensus.io/trace -google.golang.org/protobuf/internal/encoding/defval -go.opencensus.io/stats/internal crypto/rsa -go.opencensus.io/stats +google.golang.org/protobuf/proto crypto/x509/pkix vendor/golang.org/x/crypto/cryptobyte +google.golang.org/protobuf/internal/encoding/defval +go.opencensus.io/tag +go.opencensus.io/stats/internal +go.opencensus.io/stats +crypto/ecdsa github.com/prometheus/common/version html/template -github.com/google/go-cmp/cmp/cmpopts -go.opencensus.io/stats/view -google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/encoding/protodelim +google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc -crypto/ecdsa +go.opencensus.io/stats/view github.com/golang/glog -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/runtime/code -github.com/google/mtail/internal/mtail/golden -github.com/google/mtail/internal/runtime/compiler/ast google.golang.org/protobuf/internal/encoding/tag -github.com/google/mtail/internal/runtime/compiler/parser -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/protobuf/ptypes/duration +github.com/golang/protobuf/ptypes/any google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes +google.golang.org/protobuf/internal/impl +github.com/google/mtail/internal/waker +github.com/google/mtail/internal/runtime/compiler/types +github.com/google/mtail/internal/metrics google.golang.org/grpc/internal/status google.golang.org/grpc/status -github.com/google/mtail/internal/runtime/compiler/checker -github.com/google/mtail/internal/runtime/compiler/codegen +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/opt -github.com/google/mtail/cmd/mfmt +github.com/google/mtail/internal/runtime/compiler/codegen +github.com/google/mtail/internal/runtime/compiler/checker github.com/google/mtail/internal/runtime/compiler -crypto/x509 -net/textproto +github.com/google/mtail/cmd/mfmt +google.golang.org/protobuf/internal/filetype vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/stats +net/textproto +crypto/x509 github.com/prometheus/procfs -google.golang.org/protobuf/internal/filetype -vendor/golang.org/x/net/http/httpguts -mime/multipart google.golang.org/protobuf/runtime/protoimpl google.golang.org/protobuf/types/known/timestamppb +vendor/golang.org/x/net/http/httpguts +mime/multipart github.com/prometheus/client_model/go github.com/prometheus/common/model crypto/tls github.com/prometheus/client_golang/prometheus/internal net/http/httptrace net/http -expvar -github.com/prometheus/common/expfmt go.opencensus.io/trace/propagation go.opencensus.io/zpages/internal net/http/pprof +github.com/prometheus/common/expfmt +expvar go.opencensus.io/plugin/ocgrpc github.com/google/mtail/internal/tailer/logstream github.com/google/mtail/internal/testutil -github.com/google/mtail/internal/tailer go.opencensus.io/zpages github.com/prometheus/client_golang/prometheus +github.com/google/mtail/internal/tailer github.com/google/mtail/internal/runtime/vm -github.com/google/mtail/internal/exporter github.com/prometheus/client_golang/prometheus/collectors/version +github.com/google/mtail/internal/exporter github.com/prometheus/client_golang/prometheus/collectors github.com/prometheus/client_golang/prometheus/promhttp github.com/google/mtail/internal/runtime @@ -989,7 +1025,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 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 + 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 ? 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] @@ -1064,7 +1100,7 @@ --- PASS: TestHandleVarz/dimensioned (0.00s) --- PASS: TestHandleVarz/text (0.00s) PASS -ok github.com/google/mtail/internal/exporter 0.026s +ok github.com/google/mtail/internal/exporter 0.011s === RUN TestKindType --- PASS: TestKindType (0.00s) === RUN TestScalarMetric @@ -1082,7 +1118,7 @@ === RUN TestAppendLabelValue --- PASS: TestAppendLabelValue (0.00s) === RUN TestMetricJSONRoundTrip ---- PASS: TestMetricJSONRoundTrip (0.09s) +--- PASS: TestMetricJSONRoundTrip (0.04s) === RUN TestTimer --- PASS: TestTimer (0.00s) === RUN TestRemoveMetricLabelValue @@ -1102,7 +1138,7 @@ === RUN TestExpireManyMetrics --- PASS: TestExpireManyMetrics (0.00s) PASS -ok github.com/google/mtail/internal/metrics 0.113s +ok github.com/google/mtail/internal/metrics 0.044s === RUN TestDatumSetAndValue --- PASS: TestDatumSetAndValue (0.00s) === RUN TestMarshalJSON @@ -1114,16 +1150,16 @@ === RUN TestMakeBucket --- PASS: TestMakeBucket (0.00s) PASS -ok github.com/google/mtail/internal/metrics/datum 0.013s +ok github.com/google/mtail/internal/metrics/datum 0.006s ? github.com/google/mtail/internal/runtime/compiler/position [no test files] === RUN TestBuildInfo --- PASS: TestBuildInfo (0.00s) === RUN TestBasicTail ---- PASS: TestBasicTail (0.05s) +--- PASS: TestBasicTail (1.43s) === RUN TestNewLogDoesNotMatchIsIgnored ---- PASS: TestNewLogDoesNotMatchIsIgnored (0.03s) +--- PASS: TestNewLogDoesNotMatchIsIgnored (0.01s) === RUN TestBadProgramFailsCompilation ---- PASS: TestBadProgramFailsCompilation (0.01s) +--- PASS: TestBadProgramFailsCompilation (0.00s) === RUN TestExamplePrograms === RUN TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -1136,18 +1172,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 (3.43s) - --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.03s) - --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.05s) - --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (2.99s) - --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.03s) - --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.02s) - --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.09s) - --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.03s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.06s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.04s) - --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.03s) - --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.07s) +--- PASS: TestExamplePrograms (1.45s) + --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.02s) + --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.02s) + --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (1.29s) + --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.01s) + --- 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.02s) + --- 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) === RUN TestCompileExamplePrograms === RUN TestCompileExamplePrograms/apache_combined.mtail === RUN TestCompileExamplePrograms/apache_common.mtail @@ -1166,24 +1202,24 @@ === RUN TestCompileExamplePrograms/sftp.mtail === RUN TestCompileExamplePrograms/timer.mtail === RUN TestCompileExamplePrograms/vsftpd.mtail ---- PASS: TestCompileExamplePrograms (0.26s) - --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.02s) +--- PASS: TestCompileExamplePrograms (0.31s) + --- 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.04s) - --- PASS: TestCompileExamplePrograms/histogram.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/histogram.mtail (0.19s) + --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.01s) --- PASS: TestCompileExamplePrograms/linecount.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.02s) --- PASS: TestCompileExamplePrograms/nocode.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/postfix.mtail (0.04s) - --- PASS: TestCompileExamplePrograms/rails.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.01s) + --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.01s) + --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.00s) + --- PASS: TestCompileExamplePrograms/postfix.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/rails.mtail (0.00s) + --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.00s) --- PASS: TestCompileExamplePrograms/sftp.mtail (0.01s) --- PASS: TestCompileExamplePrograms/timer.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.01s) === RUN TestFilePipeStreamComparison === RUN TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -1196,18 +1232,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 (3.90s) - --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.02s) - --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (3.64s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.02s) +--- PASS: TestFilePipeStreamComparison (2.63s) + --- 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 (2.47s) + --- 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.02s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.04s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.03s) - --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.02s) - --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.05s) + --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.00s) + --- 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) === RUN TestFileSocketStreamComparison === RUN TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log === RUN TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log @@ -1231,29 +1267,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 (6.76s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (3.04s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.02s) - --- 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.02s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.05s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (3.20s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.02s) +--- PASS: TestFileSocketStreamComparison (3.19s) + --- 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.56s) + --- 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.01s) + --- 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.41s) + --- 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.02s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_log (0.04s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.03s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.02s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.05s) + --- 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) === RUN TestExecMtail exec_integration_test.go:32: mtail binary not found, skipping exec_integration_test.go:33: @@ -1261,31 +1297,31 @@ === RUN TestLogDeletion --- PASS: TestLogDeletion (0.02s) === RUN TestGlobBeforeStart ---- PASS: TestGlobBeforeStart (0.01s) +--- PASS: TestGlobBeforeStart (2.48s) === RUN TestGlobAfterStart --- PASS: TestGlobAfterStart (0.01s) === RUN TestGlobIgnoreFolder ---- PASS: TestGlobIgnoreFolder (0.01s) +--- PASS: TestGlobIgnoreFolder (1.52s) === RUN TestFilenameRegexIgnore ---- PASS: TestFilenameRegexIgnore (0.01s) +--- PASS: TestFilenameRegexIgnore (1.35s) === RUN TestGlobRelativeAfterStart ---- PASS: TestGlobRelativeAfterStart (0.05s) +--- PASS: TestGlobRelativeAfterStart (1.21s) === 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 (3.31s) + --- PASS: TestLogSoftLinkChange/race_simulation_disabled (1.00s) + --- PASS: TestLogSoftLinkChange/race_simulation_enabled (2.32s) === RUN TestLogRotation === RUN TestLogRotation/race_simulation_disabled === RUN TestLogRotation/race_simulation_enabled ---- PASS: TestLogRotation (0.07s) +--- PASS: TestLogRotation (0.06s) --- PASS: TestLogRotation/race_simulation_disabled (0.02s) --- PASS: TestLogRotation/race_simulation_enabled (0.04s) === RUN TestLogTruncation ---- PASS: TestLogTruncation (0.03s) +--- PASS: TestLogTruncation (0.08s) === RUN TestPollLogPathPatterns ---- PASS: TestPollLogPathPatterns (0.15s) +--- PASS: TestPollLogPathPatterns (0.03s) === RUN TestMultipleLinesInOneWrite --- PASS: TestMultipleLinesInOneWrite (0.02s) === RUN TestPartialLineRead @@ -1295,15 +1331,15 @@ === RUN TestNewProg --- PASS: TestNewProg (0.01s) === RUN TestProgramReloadNoDuplicateMetrics ---- PASS: TestProgramReloadNoDuplicateMetrics (0.06s) +--- PASS: TestProgramReloadNoDuplicateMetrics (0.05s) === RUN TestProgramUnloadIfDeleted ---- PASS: TestProgramUnloadIfDeleted (0.03s) +--- PASS: TestProgramUnloadIfDeleted (0.02s) === RUN TestReadFromPipe --- PASS: TestReadFromPipe (0.01s) === RUN TestReadFromSocket === RUN TestReadFromSocket/unix === RUN TestReadFromSocket/unixgram ---- PASS: TestReadFromSocket (0.05s) +--- PASS: TestReadFromSocket (0.04s) --- PASS: TestReadFromSocket/unix (0.02s) --- PASS: TestReadFromSocket/unixgram (0.02s) === RUN TestRelativeLog @@ -1311,11 +1347,11 @@ === RUN TestBasicUNIXSockets --- PASS: TestBasicUNIXSockets (0.00s) PASS -ok github.com/google/mtail/internal/mtail 15.113s +ok github.com/google/mtail/internal/mtail 19.325s === RUN TestReadTestData ---- PASS: TestReadTestData (0.01s) +--- PASS: TestReadTestData (0.00s) PASS -ok github.com/google/mtail/internal/mtail/golden 0.034s +ok github.com/google/mtail/internal/mtail/golden 0.012s === RUN TestRuntimeEndToEnd === RUN TestRuntimeEndToEnd/single-dash-parseint === RUN TestRuntimeEndToEnd/histogram @@ -1339,27 +1375,27 @@ === RUN TestRuntimeEndToEnd/subst_integer === RUN TestRuntimeEndToEnd/regexp_replace === RUN TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr ---- PASS: TestRuntimeEndToEnd (0.38s) +--- PASS: TestRuntimeEndToEnd (0.35s) --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.01s) --- PASS: TestRuntimeEndToEnd/histogram (0.02s) - --- PASS: TestRuntimeEndToEnd/numbers (0.02s) - --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.02s) - --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (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.02s) - --- PASS: TestRuntimeEndToEnd/else (0.02s) - --- PASS: TestRuntimeEndToEnd/otherwise (0.02s) + --- PASS: TestRuntimeEndToEnd/decorator (0.01s) + --- PASS: TestRuntimeEndToEnd/else (0.01s) + --- PASS: TestRuntimeEndToEnd/otherwise (0.01s) --- PASS: TestRuntimeEndToEnd/types (0.02s) --- PASS: TestRuntimeEndToEnd/filename (0.01s) --- PASS: TestRuntimeEndToEnd/logical_operators (0.02s) --- PASS: TestRuntimeEndToEnd/strcat (0.01s) - --- PASS: TestRuntimeEndToEnd/typed-comparison (0.02s) - --- PASS: TestRuntimeEndToEnd/match-expression (0.02s) - --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.02s) - --- PASS: TestRuntimeEndToEnd/stringy (0.02s) + --- PASS: TestRuntimeEndToEnd/typed-comparison (0.01s) + --- PASS: TestRuntimeEndToEnd/match-expression (0.01s) + --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.06s) + --- PASS: TestRuntimeEndToEnd/stringy (0.01s) --- PASS: TestRuntimeEndToEnd/ip-addr (0.01s) - --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.02s) - --- PASS: TestRuntimeEndToEnd/subst_integer (0.02s) + --- 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) === RUN TestNewRuntime @@ -1369,15 +1405,15 @@ === RUN TestCompileAndRun --- PASS: TestCompileAndRun (0.00s) === RUN TestLoadProg ---- PASS: TestLoadProg (0.02s) +--- PASS: TestLoadProg (0.00s) PASS -ok github.com/google/mtail/internal/runtime 0.434s +ok github.com/google/mtail/internal/runtime 0.357s === RUN TestOpcodeHasString --- PASS: TestOpcodeHasString (0.00s) === RUN TestInstrString --- PASS: TestInstrString (0.00s) PASS -ok github.com/google/mtail/internal/runtime/code 0.025s +ok github.com/google/mtail/internal/runtime/code 0.007s === RUN TestCompileParserError --- PASS: TestCompileParserError (0.00s) === RUN TestCompileCheckerError @@ -1385,13 +1421,13 @@ === RUN TestCompileCodegen --- PASS: TestCompileCodegen (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler 0.024s +ok github.com/google/mtail/internal/runtime/compiler 0.011s === RUN TestWalkPanicsOnUnknown --- PASS: TestWalkPanicsOnUnknown (0.00s) === RUN TestAstReplacement --- PASS: TestAstReplacement (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/ast 0.032s +ok github.com/google/mtail/internal/runtime/compiler/ast 0.011s === RUN TestCheckInvalidPrograms === RUN TestCheckInvalidPrograms/undefined_named_capture_group === RUN TestCheckInvalidPrograms/out_of_bounds_capref @@ -1428,7 +1464,7 @@ === RUN TestCheckInvalidPrograms/dec_non_var === RUN TestCheckInvalidPrograms/cmp_to_None === RUN TestCheckInvalidPrograms/negate_None ---- PASS: TestCheckInvalidPrograms (0.02s) +--- PASS: TestCheckInvalidPrograms (0.01s) --- PASS: TestCheckInvalidPrograms/undefined_named_capture_group (0.00s) --- PASS: TestCheckInvalidPrograms/out_of_bounds_capref (0.00s) --- PASS: TestCheckInvalidPrograms/undefined_decorator (0.00s) @@ -1497,7 +1533,7 @@ === RUN TestCheckValidPrograms/negative_numbers_in_capture_groups === RUN TestCheckValidPrograms/substitution === RUN TestCheckValidPrograms/regexp_subst ---- PASS: TestCheckValidPrograms (0.01s) +--- PASS: TestCheckValidPrograms (0.00s) --- PASS: TestCheckValidPrograms/capture_group (0.00s) --- PASS: TestCheckValidPrograms/shadowed_positionals (0.00s) --- PASS: TestCheckValidPrograms/sibling_positionals (0.00s) @@ -1539,7 +1575,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.055s +ok github.com/google/mtail/internal/runtime/compiler/checker 0.023s === RUN TestCodeGenFromSource === RUN TestCodeGenFromSource/simple_line_counter === RUN TestCodeGenFromSource/count_a @@ -1592,7 +1628,7 @@ === RUN TestCodeGenFromSource/negative_numbers_in_capture_groups === RUN TestCodeGenFromSource/substitution === RUN TestCodeGenFromSource/const_term_as_pattern ---- PASS: TestCodeGenFromSource (0.02s) +--- PASS: TestCodeGenFromSource (0.01s) --- PASS: TestCodeGenFromSource/simple_line_counter (0.00s) --- PASS: TestCodeGenFromSource/count_a (0.00s) --- PASS: TestCodeGenFromSource/strptime_and_capref (0.00s) @@ -1651,11 +1687,11 @@ --- PASS: TestCodeGenFromAST/subst (0.00s) --- PASS: TestCodeGenFromAST/regexp_subst (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/codegen 0.047s +ok github.com/google/mtail/internal/runtime/compiler/codegen 0.017s === RUN TestNilErrorPosition --- PASS: TestNilErrorPosition (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/errors 0.007s +ok github.com/google/mtail/internal/runtime/compiler/errors 0.004s === RUN TestOptimiser === RUN TestOptimiser/int_add === RUN TestOptimiser/float_mul @@ -1701,7 +1737,7 @@ === RUN TestConstFoldQuickFloatAddSub --- PASS: TestConstFoldQuickFloatAddSub (0.00s) === RUN TestConstFoldQuickMixedAddSub ---- PASS: TestConstFoldQuickMixedAddSub (0.01s) +--- PASS: TestConstFoldQuickMixedAddSub (0.00s) === RUN TestConstFoldQuickFloatMulDiv --- PASS: TestConstFoldQuickFloatMulDiv (0.00s) === RUN TestConstFoldQuickIntModAddition @@ -1711,7 +1747,7 @@ === RUN TestConstFoldQuickMixedPowProduct --- PASS: TestConstFoldQuickMixedPowProduct (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/opt 0.069s +ok github.com/google/mtail/internal/runtime/compiler/opt 0.023s === RUN TestLex === RUN TestLex/empty === RUN TestLex/spaces @@ -1738,7 +1774,7 @@ === RUN TestLex/unexpected_char === RUN TestLex/unterminated_regex === RUN TestLex/unterminated_quoted_string ---- PASS: TestLex (0.01s) +--- PASS: TestLex (0.00s) --- PASS: TestLex/empty (0.00s) --- PASS: TestLex/spaces (0.00s) --- PASS: TestLex/newlines (0.00s) @@ -1935,17 +1971,17 @@ === RUN TestKindHasString --- PASS: TestKindHasString (0.00s) === RUN TestTokenString ---- PASS: TestTokenString (0.01s) +--- PASS: TestTokenString (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/parser 0.051s +ok github.com/google/mtail/internal/runtime/compiler/parser 0.024s === RUN TestInsertLookup --- PASS: TestInsertLookup (0.00s) === RUN TestInsertLookupQuick ---- PASS: TestInsertLookupQuick (3.42s) +--- PASS: TestInsertLookupQuick (1.36s) === RUN TestNestedScope --- PASS: TestNestedScope (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/symbol 3.463s +ok github.com/google/mtail/internal/runtime/compiler/symbol 1.367s === RUN TestTypeUnification === RUN TestTypeUnification/None_None === RUN TestTypeUnification/String_String @@ -1985,7 +2021,7 @@ === RUN TestTypeUnification/Int_Int|Float === RUN TestTypeUnification/Int|Float_Float === RUN TestTypeUnification/Float_Int|Float ---- PASS: TestTypeUnification (0.01s) +--- PASS: TestTypeUnification (0.00s) --- PASS: TestTypeUnification/None_None (0.00s) --- PASS: TestTypeUnification/String_String (0.00s) --- PASS: TestTypeUnification/Int_Int (0.00s) @@ -2057,7 +2093,7 @@ === RUN TestAsTypeError --- PASS: TestAsTypeError (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/types 0.025s +ok github.com/google/mtail/internal/runtime/compiler/types 0.010s === RUN TestInstrs === RUN TestInstrs/match === RUN TestInstrs/cmp_lt @@ -2121,7 +2157,7 @@ === RUN TestInstrs/fcmp_gt_false === RUN TestInstrs/scmp_eq_false === RUN TestInstrs/subst ---- PASS: TestInstrs (0.01s) +--- PASS: TestInstrs (0.00s) --- PASS: TestInstrs/match (0.00s) --- PASS: TestInstrs/cmp_lt (0.00s) --- PASS: TestInstrs/cmp_eq (0.00s) @@ -2217,28 +2253,28 @@ === RUN TestTimestampInstr --- PASS: TestTimestampInstr (0.00s) PASS -ok github.com/google/mtail/internal/runtime/vm 0.063s +ok github.com/google/mtail/internal/runtime/vm 0.015s === RUN TestTail --- PASS: TestTail (0.00s) === RUN TestTailErrors --- PASS: TestTailErrors (0.00s) === RUN TestHandleLogUpdate ---- PASS: TestHandleLogUpdate (0.01s) +--- PASS: TestHandleLogUpdate (0.82s) === RUN TestHandleLogTruncate ---- PASS: TestHandleLogTruncate (0.01s) +--- PASS: TestHandleLogTruncate (1.04s) === RUN TestHandleLogUpdatePartialLine ---- PASS: TestHandleLogUpdatePartialLine (0.02s) +--- PASS: TestHandleLogUpdatePartialLine (1.39s) === RUN TestTailerUnreadableFile ---- PASS: TestTailerUnreadableFile (0.01s) +--- PASS: TestTailerUnreadableFile (0.52s) === 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.57s) PASS -ok github.com/google/mtail/internal/tailer 0.077s +ok github.com/google/mtail/internal/tailer 4.344s === RUN TestNewErrors --- PASS: TestNewErrors (0.00s) === RUN TestDgramStreamReadCompletedBecauseSocketClosed @@ -2254,21 +2290,21 @@ --- PASS: TestDgramStreamReadCompletedBecauseCancel/unixgram (0.00s) --- PASS: TestDgramStreamReadCompletedBecauseCancel/udp (0.00s) === RUN TestFileStreamRead ---- PASS: TestFileStreamRead (0.01s) +--- PASS: TestFileStreamRead (0.82s) === RUN TestFileStreamReadNonSingleByteEnd ---- PASS: TestFileStreamReadNonSingleByteEnd (0.03s) +--- PASS: TestFileStreamReadNonSingleByteEnd (0.41s) === RUN TestStreamDoesntBreakOnCorruptRune ---- PASS: TestStreamDoesntBreakOnCorruptRune (0.01s) +--- PASS: TestStreamDoesntBreakOnCorruptRune (0.63s) === RUN TestFileStreamTruncation ---- PASS: TestFileStreamTruncation (0.01s) +--- PASS: TestFileStreamTruncation (0.77s) === RUN TestFileStreamFinishedBecauseCancel ---- PASS: TestFileStreamFinishedBecauseCancel (0.00s) +--- PASS: TestFileStreamFinishedBecauseCancel (0.72s) === RUN TestFileStreamPartialRead ---- PASS: TestFileStreamPartialRead (0.01s) +--- PASS: TestFileStreamPartialRead (1.12s) === RUN TestFileStreamRotation ---- PASS: TestFileStreamRotation (0.01s) +--- PASS: TestFileStreamRotation (0.76s) === RUN TestFileStreamURL ---- PASS: TestFileStreamURL (0.00s) +--- PASS: TestFileStreamURL (0.44s) === RUN TestFileStreamOpenFailure --- PASS: TestFileStreamOpenFailure (0.00s) === RUN TestPipeStreamReadCompletedBecauseClosed @@ -2290,7 +2326,7 @@ --- PASS: TestSocketStreamReadCompletedBecauseCancel/unix (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseCancel/tcp (0.00s) PASS -ok github.com/google/mtail/internal/tailer/logstream 0.109s +ok github.com/google/mtail/internal/tailer/logstream 5.680s === RUN TestDoOrTimeoutNeverOK --- PASS: TestDoOrTimeoutNeverOK (0.01s) === RUN TestDoOrTimeoutAlwaysOK @@ -2300,7 +2336,7 @@ === RUN TestDoOrTimeoutAlwaysErr --- PASS: TestDoOrTimeoutAlwaysErr (0.00s) PASS -ok github.com/google/mtail/internal/testutil 0.085s +ok github.com/google/mtail/internal/testutil 0.072s === RUN TestTestWakerWakes --- PASS: TestTestWakerWakes (0.00s) === RUN TestTestWakerTwoWakees @@ -2310,14 +2346,15 @@ === RUN TestTimedWakerWakes --- PASS: TestTimedWakerWakes (0.01s) PASS -ok github.com/google/mtail/internal/waker 0.038s +ok github.com/google/mtail/internal/waker 0.018s # 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 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 + 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 ? 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] +? github.com/google/mtail/internal/runtime/compiler/position [no test files] === RUN TestCreateExporter --- PASS: TestCreateExporter (0.00s) === RUN TestNewErrors @@ -2357,7 +2394,7 @@ === RUN TestHandlePrometheus/2_help_with_label === RUN TestHandlePrometheus/histo === RUN TestHandlePrometheus/histo-count-eq-inf ---- PASS: TestHandlePrometheus (0.02s) +--- PASS: TestHandlePrometheus (0.01s) --- PASS: TestHandlePrometheus/empty (0.00s) --- PASS: TestHandlePrometheus/single (0.00s) --- PASS: TestHandlePrometheus/with_prog_label (0.00s) @@ -2389,7 +2426,7 @@ --- PASS: TestHandleVarz/dimensioned (0.00s) --- PASS: TestHandleVarz/text (0.00s) PASS -ok github.com/google/mtail/internal/exporter 1.095s +ok github.com/google/mtail/internal/exporter 1.046s === RUN TestKindType --- PASS: TestKindType (0.00s) === RUN TestScalarMetric @@ -2407,7 +2444,7 @@ === RUN TestAppendLabelValue --- PASS: TestAppendLabelValue (0.00s) === RUN TestMetricJSONRoundTrip ---- PASS: TestMetricJSONRoundTrip (0.47s) +--- PASS: TestMetricJSONRoundTrip (0.26s) === RUN TestTimer --- PASS: TestTimer (0.00s) === RUN TestRemoveMetricLabelValue @@ -2427,7 +2464,7 @@ === RUN TestExpireManyMetrics --- PASS: TestExpireManyMetrics (0.00s) PASS -ok github.com/google/mtail/internal/metrics 1.526s +ok github.com/google/mtail/internal/metrics 1.290s === RUN TestDatumSetAndValue --- PASS: TestDatumSetAndValue (0.00s) === RUN TestMarshalJSON @@ -2439,12 +2476,11 @@ === RUN TestMakeBucket --- PASS: TestMakeBucket (0.00s) PASS -ok github.com/google/mtail/internal/metrics/datum 1.051s -? github.com/google/mtail/internal/runtime/compiler/position [no test files] +ok github.com/google/mtail/internal/metrics/datum 1.024s === RUN TestBuildInfo --- PASS: TestBuildInfo (0.00s) === RUN TestBasicTail ---- PASS: TestBasicTail (0.48s) +--- PASS: TestBasicTail (0.03s) === RUN TestNewLogDoesNotMatchIsIgnored --- PASS: TestNewLogDoesNotMatchIsIgnored (0.01s) === RUN TestBadProgramFailsCompilation @@ -2461,18 +2497,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 (52.67s) - --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.24s) - --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.16s) - --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (51.24s) - --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.11s) - --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.09s) - --- 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.08s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.21s) - --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.16s) - --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.09s) - --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.25s) +--- PASS: TestExamplePrograms (21.75s) + --- PASS: TestExamplePrograms/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.11s) + --- PASS: TestExamplePrograms/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.10s) + --- PASS: TestExamplePrograms/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (21.08s) + --- PASS: TestExamplePrograms/examples/ntpd.mtail_on_testdata/ntp4 (0.05s) + --- PASS: TestExamplePrograms/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.04s) + --- PASS: TestExamplePrograms/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.02s) + --- PASS: TestExamplePrograms/examples/apache_common.mtail_on_testdata/apache-common.log (0.04s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.10s) + --- PASS: TestExamplePrograms/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.07s) + --- PASS: TestExamplePrograms/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.04s) + --- PASS: TestExamplePrograms/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.11s) === RUN TestCompileExamplePrograms === RUN TestCompileExamplePrograms/apache_combined.mtail === RUN TestCompileExamplePrograms/apache_common.mtail @@ -2491,24 +2527,24 @@ === RUN TestCompileExamplePrograms/sftp.mtail === RUN TestCompileExamplePrograms/timer.mtail === RUN TestCompileExamplePrograms/vsftpd.mtail ---- PASS: TestCompileExamplePrograms (0.95s) - --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.03s) - --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.14s) +--- PASS: TestCompileExamplePrograms (0.46s) + --- PASS: TestCompileExamplePrograms/apache_combined.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/apache_common.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/apache_metrics.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/dhcpd.mtail (0.07s) --- PASS: TestCompileExamplePrograms/histogram.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/linecount.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.14s) + --- PASS: TestCompileExamplePrograms/lighttpd.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/linecount.mtail (0.00s) + --- PASS: TestCompileExamplePrograms/mysql_slowqueries.mtail (0.06s) --- PASS: TestCompileExamplePrograms/nocode.mtail (0.00s) - --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.07s) - --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/postfix.mtail (0.14s) - --- PASS: TestCompileExamplePrograms/rails.mtail (0.02s) - --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.04s) - --- PASS: TestCompileExamplePrograms/sftp.mtail (0.05s) - --- PASS: TestCompileExamplePrograms/timer.mtail (0.01s) - --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.10s) + --- PASS: TestCompileExamplePrograms/ntpd.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/ntpd_peerstats.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/postfix.mtail (0.08s) + --- PASS: TestCompileExamplePrograms/rails.mtail (0.01s) + --- PASS: TestCompileExamplePrograms/rsyncd.mtail (0.02s) + --- PASS: TestCompileExamplePrograms/sftp.mtail (0.03s) + --- PASS: TestCompileExamplePrograms/timer.mtail (0.00s) + --- PASS: TestCompileExamplePrograms/vsftpd.mtail (0.05s) === RUN TestFilePipeStreamComparison === RUN TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log === RUN TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log @@ -2521,18 +2557,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 (85.13s) - --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.22s) - --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.15s) - --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (83.17s) +--- PASS: TestFilePipeStreamComparison (26.38s) + --- PASS: TestFilePipeStreamComparison/examples/rsyncd.mtail_on_testdata/rsyncd.log (0.09s) + --- PASS: TestFilePipeStreamComparison/examples/sftp.mtail_on_testdata/sftp_chroot.log (0.06s) + --- PASS: TestFilePipeStreamComparison/examples/dhcpd.mtail_on_testdata/anonymised_dhcpd_log (25.22s) --- PASS: TestFilePipeStreamComparison/examples/ntpd.mtail_on_testdata/ntp4 (0.15s) - --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.13s) - --- PASS: TestFilePipeStreamComparison/examples/apache_combined.mtail_on_testdata/apache-combined.log (0.10s) - --- PASS: TestFilePipeStreamComparison/examples/apache_common.mtail_on_testdata/apache-common.log (0.16s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.29s) - --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.16s) - --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.16s) - --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.41s) + --- PASS: TestFilePipeStreamComparison/examples/ntpd_peerstats.mtail_on_testdata/xntp3_peerstats (0.11s) + --- 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.11s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_log (0.19s) + --- PASS: TestFilePipeStreamComparison/examples/vsftpd.mtail_on_testdata/vsftpd_xferlog (0.07s) + --- PASS: TestFilePipeStreamComparison/examples/lighttpd.mtail_on_testdata/lighttpd_access.log (0.08s) + --- PASS: TestFilePipeStreamComparison/examples/mysql_slowqueries.mtail_on_testdata/mysql_slowqueries.log (0.23s) === RUN TestFileSocketStreamComparison === RUN TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log === RUN TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log @@ -2556,29 +2592,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 (188.14s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.40s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.33s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (108.22s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.10s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.07s) - --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.05s) - --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.18s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.50s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.27s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.54s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.42s) - --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.41s) - --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.43s) - --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (74.83s) - --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.07s) - --- 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.05s) - --- 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.17s) - --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.10s) - --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.09s) - --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.75s) +--- PASS: TestFileSocketStreamComparison (71.99s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unixgram://testdata/rsyncd.log (0.28s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unixgram://testdata/sftp_chroot.log (0.18s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unixgram://testdata/anonymised_dhcpd_log (35.21s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unixgram://testdata/ntp4 (0.07s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unixgram://testdata/xntp3_peerstats (0.06s) + --- PASS: TestFileSocketStreamComparison/examples/apache_combined.mtail_on_unixgram://testdata/apache-combined.log (0.04s) + --- PASS: TestFileSocketStreamComparison/examples/apache_common.mtail_on_unixgram://testdata/apache-common.log (0.08s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_log (0.19s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unixgram://testdata/vsftpd_xferlog (0.09s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unixgram://testdata/lighttpd_access.log (0.07s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unixgram://testdata/mysql_slowqueries.log (0.21s) + --- PASS: TestFileSocketStreamComparison/examples/rsyncd.mtail_on_unix://testdata/rsyncd.log (0.20s) + --- PASS: TestFileSocketStreamComparison/examples/sftp.mtail_on_unix://testdata/sftp_chroot.log (0.15s) + --- PASS: TestFileSocketStreamComparison/examples/dhcpd.mtail_on_unix://testdata/anonymised_dhcpd_log (34.70s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd.mtail_on_unix://testdata/ntp4 (0.06s) + --- PASS: TestFileSocketStreamComparison/examples/ntpd_peerstats.mtail_on_unix://testdata/xntp3_peerstats (0.03s) + --- 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.10s) + --- PASS: TestFileSocketStreamComparison/examples/vsftpd.mtail_on_unix://testdata/vsftpd_xferlog (0.06s) + --- PASS: TestFileSocketStreamComparison/examples/lighttpd.mtail_on_unix://testdata/lighttpd_access.log (0.04s) + --- PASS: TestFileSocketStreamComparison/examples/mysql_slowqueries.mtail_on_unix://testdata/mysql_slowqueries.log (0.12s) === RUN TestExecMtail exec_integration_test.go:32: mtail binary not found, skipping exec_integration_test.go:33: @@ -2586,61 +2622,61 @@ === RUN TestLogDeletion --- PASS: TestLogDeletion (0.03s) === RUN TestGlobBeforeStart ---- PASS: TestGlobBeforeStart (1.09s) +--- PASS: TestGlobBeforeStart (0.02s) === RUN TestGlobAfterStart --- PASS: TestGlobAfterStart (0.02s) === RUN TestGlobIgnoreFolder ---- PASS: TestGlobIgnoreFolder (0.16s) +--- PASS: TestGlobIgnoreFolder (0.01s) === RUN TestFilenameRegexIgnore ---- PASS: TestFilenameRegexIgnore (0.02s) +--- PASS: TestFilenameRegexIgnore (0.01s) === RUN TestGlobRelativeAfterStart ---- PASS: TestGlobRelativeAfterStart (0.08s) +--- PASS: TestGlobRelativeAfterStart (0.05s) === RUN TestLogSoftLinkChange === RUN TestLogSoftLinkChange/race_simulation_disabled === RUN TestLogSoftLinkChange/race_simulation_enabled ---- PASS: TestLogSoftLinkChange (0.18s) - --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.10s) - --- PASS: TestLogSoftLinkChange/race_simulation_enabled (0.08s) +--- PASS: TestLogSoftLinkChange (2.67s) + --- PASS: TestLogSoftLinkChange/race_simulation_disabled (0.70s) + --- PASS: TestLogSoftLinkChange/race_simulation_enabled (1.97s) === RUN TestLogRotation === RUN TestLogRotation/race_simulation_disabled === RUN TestLogRotation/race_simulation_enabled ---- PASS: TestLogRotation (0.12s) - --- PASS: TestLogRotation/race_simulation_disabled (0.05s) - --- PASS: TestLogRotation/race_simulation_enabled (0.07s) +--- PASS: TestLogRotation (0.07s) + --- PASS: TestLogRotation/race_simulation_disabled (0.02s) + --- PASS: TestLogRotation/race_simulation_enabled (0.05s) === RUN TestLogTruncation ---- PASS: TestLogTruncation (0.05s) +--- PASS: TestLogTruncation (0.03s) === RUN TestPollLogPathPatterns ---- PASS: TestPollLogPathPatterns (0.04s) +--- PASS: TestPollLogPathPatterns (0.03s) === RUN TestMultipleLinesInOneWrite ---- PASS: TestMultipleLinesInOneWrite (0.06s) +--- PASS: TestMultipleLinesInOneWrite (0.03s) === RUN TestPartialLineRead ---- PASS: TestPartialLineRead (0.03s) +--- PASS: TestPartialLineRead (0.45s) === RUN TestPermissionDeniedOnLog ---- PASS: TestPermissionDeniedOnLog (0.02s) +--- PASS: TestPermissionDeniedOnLog (0.01s) === RUN TestNewProg ---- PASS: TestNewProg (0.02s) +--- PASS: TestNewProg (0.01s) === RUN TestProgramReloadNoDuplicateMetrics ---- PASS: TestProgramReloadNoDuplicateMetrics (0.08s) +--- PASS: TestProgramReloadNoDuplicateMetrics (0.06s) === RUN TestProgramUnloadIfDeleted ---- PASS: TestProgramUnloadIfDeleted (0.05s) +--- PASS: TestProgramUnloadIfDeleted (0.03s) === RUN TestReadFromPipe ---- PASS: TestReadFromPipe (0.02s) +--- PASS: TestReadFromPipe (0.01s) === RUN TestReadFromSocket === RUN TestReadFromSocket/unix === RUN TestReadFromSocket/unixgram ---- PASS: TestReadFromSocket (0.07s) - --- PASS: TestReadFromSocket/unix (0.03s) - --- PASS: TestReadFromSocket/unixgram (0.03s) +--- PASS: TestReadFromSocket (0.05s) + --- PASS: TestReadFromSocket/unix (0.02s) + --- PASS: TestReadFromSocket/unixgram (0.02s) === RUN TestRelativeLog ---- PASS: TestRelativeLog (0.03s) +--- PASS: TestRelativeLog (0.02s) === RUN TestBasicUNIXSockets ---- PASS: TestBasicUNIXSockets (0.01s) +--- PASS: TestBasicUNIXSockets (0.00s) PASS -ok github.com/google/mtail/internal/mtail 330.694s +ok github.com/google/mtail/internal/mtail 125.302s === RUN TestReadTestData ---- PASS: TestReadTestData (0.05s) +--- PASS: TestReadTestData (0.02s) PASS -ok github.com/google/mtail/internal/mtail/golden 1.185s +ok github.com/google/mtail/internal/mtail/golden 1.056s === RUN TestRuntimeEndToEnd === RUN TestRuntimeEndToEnd/single-dash-parseint === RUN TestRuntimeEndToEnd/histogram @@ -2664,28 +2700,28 @@ === RUN TestRuntimeEndToEnd/subst_integer === RUN TestRuntimeEndToEnd/regexp_replace === RUN TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr ---- PASS: TestRuntimeEndToEnd (0.74s) - --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.03s) - --- PASS: TestRuntimeEndToEnd/histogram (0.06s) +--- PASS: TestRuntimeEndToEnd (0.57s) + --- PASS: TestRuntimeEndToEnd/single-dash-parseint (0.02s) + --- PASS: TestRuntimeEndToEnd/histogram (0.05s) --- PASS: TestRuntimeEndToEnd/numbers (0.02s) - --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.04s) - --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.03s) + --- PASS: TestRuntimeEndToEnd/parse_a_hyphen (0.02s) + --- PASS: TestRuntimeEndToEnd/parse_around_a_hyphen (0.02s) --- PASS: TestRuntimeEndToEnd/add_assign_float (0.02s) - --- PASS: TestRuntimeEndToEnd/decorator (0.05s) + --- PASS: TestRuntimeEndToEnd/decorator (0.03s) --- PASS: TestRuntimeEndToEnd/else (0.03s) --- PASS: TestRuntimeEndToEnd/otherwise (0.03s) - --- PASS: TestRuntimeEndToEnd/types (0.06s) + --- PASS: TestRuntimeEndToEnd/types (0.04s) --- PASS: TestRuntimeEndToEnd/filename (0.02s) - --- PASS: TestRuntimeEndToEnd/logical_operators (0.04s) + --- PASS: TestRuntimeEndToEnd/logical_operators (0.03s) --- 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/typed-comparison (0.03s) + --- PASS: TestRuntimeEndToEnd/match-expression (0.03s) + --- PASS: TestRuntimeEndToEnd/metric-as-rvalue (0.02s) + --- 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.03s) + --- PASS: TestRuntimeEndToEnd/subst_timestamp (0.03s) + --- PASS: TestRuntimeEndToEnd/subst_integer (0.02s) + --- PASS: TestRuntimeEndToEnd/regexp_replace (0.02s) --- PASS: TestRuntimeEndToEnd/match_a_pattern_in_a_binary_expr (0.02s) === RUN TestNewRuntime --- PASS: TestNewRuntime (0.00s) @@ -2694,29 +2730,29 @@ === RUN TestCompileAndRun --- PASS: TestCompileAndRun (0.00s) === RUN TestLoadProg ---- PASS: TestLoadProg (0.06s) +--- PASS: TestLoadProg (0.00s) PASS -ok github.com/google/mtail/internal/runtime 1.992s +ok github.com/google/mtail/internal/runtime 1.609s === RUN TestOpcodeHasString --- PASS: TestOpcodeHasString (0.00s) === RUN TestInstrString --- PASS: TestInstrString (0.00s) PASS -ok github.com/google/mtail/internal/runtime/code 1.122s +ok github.com/google/mtail/internal/runtime/code 1.034s === RUN TestCompileParserError --- PASS: TestCompileParserError (0.00s) === RUN TestCompileCheckerError --- PASS: TestCompileCheckerError (0.00s) === RUN TestCompileCodegen ---- PASS: TestCompileCodegen (0.01s) +--- PASS: TestCompileCodegen (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler 1.120s +ok github.com/google/mtail/internal/runtime/compiler 1.032s === RUN TestWalkPanicsOnUnknown --- PASS: TestWalkPanicsOnUnknown (0.00s) === RUN TestAstReplacement --- PASS: TestAstReplacement (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/ast 1.117s +ok github.com/google/mtail/internal/runtime/compiler/ast 1.025s === RUN TestCheckInvalidPrograms === RUN TestCheckInvalidPrograms/undefined_named_capture_group === RUN TestCheckInvalidPrograms/out_of_bounds_capref @@ -2753,7 +2789,7 @@ === RUN TestCheckInvalidPrograms/dec_non_var === RUN TestCheckInvalidPrograms/cmp_to_None === RUN TestCheckInvalidPrograms/negate_None ---- PASS: TestCheckInvalidPrograms (0.07s) +--- PASS: TestCheckInvalidPrograms (0.03s) --- PASS: TestCheckInvalidPrograms/undefined_named_capture_group (0.00s) --- PASS: TestCheckInvalidPrograms/out_of_bounds_capref (0.00s) --- PASS: TestCheckInvalidPrograms/undefined_decorator (0.00s) @@ -2864,7 +2900,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.205s +ok github.com/google/mtail/internal/runtime/compiler/checker 1.076s === RUN TestCodeGenFromSource === RUN TestCodeGenFromSource/simple_line_counter === RUN TestCodeGenFromSource/count_a @@ -2917,7 +2953,7 @@ === RUN TestCodeGenFromSource/negative_numbers_in_capture_groups === RUN TestCodeGenFromSource/substitution === RUN TestCodeGenFromSource/const_term_as_pattern ---- PASS: TestCodeGenFromSource (0.08s) +--- PASS: TestCodeGenFromSource (0.07s) --- PASS: TestCodeGenFromSource/simple_line_counter (0.00s) --- PASS: TestCodeGenFromSource/count_a (0.00s) --- PASS: TestCodeGenFromSource/strptime_and_capref (0.00s) @@ -2976,11 +3012,11 @@ --- PASS: TestCodeGenFromAST/subst (0.00s) --- PASS: TestCodeGenFromAST/regexp_subst (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/codegen 1.127s +ok github.com/google/mtail/internal/runtime/compiler/codegen 1.096s === RUN TestNilErrorPosition --- PASS: TestNilErrorPosition (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/errors 1.105s +ok github.com/google/mtail/internal/runtime/compiler/errors 1.021s === RUN TestOptimiser === RUN TestOptimiser/int_add === RUN TestOptimiser/float_mul @@ -3004,25 +3040,25 @@ --- PASS: TestOptimiserErrors/integer_mod_by_zero (0.00s) --- PASS: TestOptimiserErrors/float_mod_by_zero (0.00s) === RUN TestConstFoldQuickIntComm -=== RUN TestConstFoldQuickIntComm/mul === RUN TestConstFoldQuickIntComm/add ---- PASS: TestConstFoldQuickIntComm (0.02s) - --- PASS: TestConstFoldQuickIntComm/mul (0.01s) +=== RUN TestConstFoldQuickIntComm/mul +--- PASS: TestConstFoldQuickIntComm (0.01s) --- PASS: TestConstFoldQuickIntComm/add (0.01s) + --- PASS: TestConstFoldQuickIntComm/mul (0.00s) === RUN TestConstFoldQuickFloatComm -=== RUN TestConstFoldQuickFloatComm/add === RUN TestConstFoldQuickFloatComm/mul ---- PASS: TestConstFoldQuickFloatComm (0.02s) - --- PASS: TestConstFoldQuickFloatComm/add (0.01s) - --- PASS: TestConstFoldQuickFloatComm/mul (0.01s) +=== RUN TestConstFoldQuickFloatComm/add +--- PASS: TestConstFoldQuickFloatComm (0.01s) + --- PASS: TestConstFoldQuickFloatComm/mul (0.00s) + --- PASS: TestConstFoldQuickFloatComm/add (0.00s) === RUN TestConstFoldQuickMixedComm === RUN TestConstFoldQuickMixedComm/add === RUN TestConstFoldQuickMixedComm/mul --- PASS: TestConstFoldQuickMixedComm (0.01s) - --- PASS: TestConstFoldQuickMixedComm/add (0.01s) - --- PASS: TestConstFoldQuickMixedComm/mul (0.01s) + --- PASS: TestConstFoldQuickMixedComm/add (0.00s) + --- PASS: TestConstFoldQuickMixedComm/mul (0.00s) === RUN TestConstFoldQuickIntAddSub ---- PASS: TestConstFoldQuickIntAddSub (0.01s) +--- PASS: TestConstFoldQuickIntAddSub (0.00s) === RUN TestConstFoldQuickFloatAddSub --- PASS: TestConstFoldQuickFloatAddSub (0.01s) === RUN TestConstFoldQuickMixedAddSub @@ -3036,7 +3072,7 @@ === RUN TestConstFoldQuickMixedPowProduct --- PASS: TestConstFoldQuickMixedPowProduct (0.01s) PASS -ok github.com/google/mtail/internal/runtime/compiler/opt 1.236s +ok github.com/google/mtail/internal/runtime/compiler/opt 1.115s === RUN TestLex === RUN TestLex/empty === RUN TestLex/spaces @@ -3063,7 +3099,7 @@ === RUN TestLex/unexpected_char === RUN TestLex/unterminated_regex === RUN TestLex/unterminated_quoted_string ---- PASS: TestLex (0.03s) +--- PASS: TestLex (0.02s) --- PASS: TestLex/empty (0.00s) --- PASS: TestLex/spaces (0.00s) --- PASS: TestLex/newlines (0.00s) @@ -3153,7 +3189,7 @@ === RUN TestParserRoundTrip/stop === RUN TestParserRoundTrip/substitution === RUN TestParserRoundTrip/pattern_in_arg_expr_list ---- PASS: TestParserRoundTrip (0.07s) +--- PASS: TestParserRoundTrip (0.05s) --- PASS: TestParserRoundTrip/empty (0.00s) --- PASS: TestParserRoundTrip/newline (0.00s) --- PASS: TestParserRoundTrip/declare_counter (0.00s) @@ -3260,17 +3296,17 @@ === RUN TestKindHasString --- PASS: TestKindHasString (0.00s) === RUN TestTokenString ---- PASS: TestTokenString (0.02s) +--- PASS: TestTokenString (0.01s) PASS -ok github.com/google/mtail/internal/runtime/compiler/parser 1.214s +ok github.com/google/mtail/internal/runtime/compiler/parser 1.123s === RUN TestInsertLookup --- PASS: TestInsertLookup (0.00s) === RUN TestInsertLookupQuick ---- PASS: TestInsertLookupQuick (12.20s) +--- PASS: TestInsertLookupQuick (6.43s) === RUN TestNestedScope --- PASS: TestNestedScope (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/symbol 13.301s +ok github.com/google/mtail/internal/runtime/compiler/symbol 7.461s === RUN TestTypeUnification === RUN TestTypeUnification/None_None === RUN TestTypeUnification/String_String @@ -3382,7 +3418,7 @@ === RUN TestAsTypeError --- PASS: TestAsTypeError (0.00s) PASS -ok github.com/google/mtail/internal/runtime/compiler/types 1.174s +ok github.com/google/mtail/internal/runtime/compiler/types 1.046s === RUN TestInstrs === RUN TestInstrs/match === RUN TestInstrs/cmp_lt @@ -3446,7 +3482,7 @@ === RUN TestInstrs/fcmp_gt_false === RUN TestInstrs/scmp_eq_false === RUN TestInstrs/subst ---- PASS: TestInstrs (0.05s) +--- PASS: TestInstrs (0.03s) --- PASS: TestInstrs/match (0.00s) --- PASS: TestInstrs/cmp_lt (0.00s) --- PASS: TestInstrs/cmp_eq (0.00s) @@ -3542,35 +3578,35 @@ === RUN TestTimestampInstr --- PASS: TestTimestampInstr (0.00s) PASS -ok github.com/google/mtail/internal/runtime/vm 1.155s +ok github.com/google/mtail/internal/runtime/vm 1.071s === RUN TestTail --- PASS: TestTail (0.00s) === RUN TestTailErrors --- PASS: TestTailErrors (0.00s) === RUN TestHandleLogUpdate ---- PASS: TestHandleLogUpdate (0.21s) +--- PASS: TestHandleLogUpdate (0.01s) === RUN TestHandleLogTruncate ---- PASS: TestHandleLogTruncate (0.26s) +--- PASS: TestHandleLogTruncate (0.01s) === RUN TestHandleLogUpdatePartialLine ---- PASS: TestHandleLogUpdatePartialLine (1.30s) +--- PASS: TestHandleLogUpdatePartialLine (0.02s) === RUN TestTailerUnreadableFile ---- PASS: TestTailerUnreadableFile (0.42s) +--- 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.11s) +--- PASS: TestTailerOpenRetries (0.01s) PASS -ok github.com/google/mtail/internal/tailer 3.452s +ok github.com/google/mtail/internal/tailer 1.095s === RUN TestNewErrors --- PASS: TestNewErrors (0.00s) === RUN TestDgramStreamReadCompletedBecauseSocketClosed === RUN TestDgramStreamReadCompletedBecauseSocketClosed/unixgram === RUN TestDgramStreamReadCompletedBecauseSocketClosed/udp ---- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.05s) - --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.04s) +--- PASS: TestDgramStreamReadCompletedBecauseSocketClosed (0.01s) + --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/unixgram (0.00s) --- PASS: TestDgramStreamReadCompletedBecauseSocketClosed/udp (0.00s) === RUN TestDgramStreamReadCompletedBecauseCancel === RUN TestDgramStreamReadCompletedBecauseCancel/unixgram @@ -3579,21 +3615,21 @@ --- PASS: TestDgramStreamReadCompletedBecauseCancel/unixgram (0.00s) --- PASS: TestDgramStreamReadCompletedBecauseCancel/udp (0.00s) === RUN TestFileStreamRead ---- PASS: TestFileStreamRead (0.20s) +--- PASS: TestFileStreamRead (0.00s) === RUN TestFileStreamReadNonSingleByteEnd ---- PASS: TestFileStreamReadNonSingleByteEnd (0.16s) +--- PASS: TestFileStreamReadNonSingleByteEnd (0.02s) === RUN TestStreamDoesntBreakOnCorruptRune ---- PASS: TestStreamDoesntBreakOnCorruptRune (0.18s) +--- PASS: TestStreamDoesntBreakOnCorruptRune (0.01s) === RUN TestFileStreamTruncation ---- PASS: TestFileStreamTruncation (0.63s) +--- PASS: TestFileStreamTruncation (0.01s) === RUN TestFileStreamFinishedBecauseCancel ---- PASS: TestFileStreamFinishedBecauseCancel (0.89s) +--- PASS: TestFileStreamFinishedBecauseCancel (0.01s) === RUN TestFileStreamPartialRead ---- PASS: TestFileStreamPartialRead (0.23s) +--- PASS: TestFileStreamPartialRead (0.01s) === RUN TestFileStreamRotation --- PASS: TestFileStreamRotation (0.01s) === RUN TestFileStreamURL ---- PASS: TestFileStreamURL (0.00s) +--- PASS: TestFileStreamURL (0.01s) === RUN TestFileStreamOpenFailure --- PASS: TestFileStreamOpenFailure (0.00s) === RUN TestPipeStreamReadCompletedBecauseClosed @@ -3605,17 +3641,17 @@ === RUN TestSocketStreamReadCompletedBecauseSocketClosed === RUN TestSocketStreamReadCompletedBecauseSocketClosed/unix === RUN TestSocketStreamReadCompletedBecauseSocketClosed/tcp ---- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.01s) +--- PASS: TestSocketStreamReadCompletedBecauseSocketClosed (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/unix (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseSocketClosed/tcp (0.00s) === RUN TestSocketStreamReadCompletedBecauseCancel === RUN TestSocketStreamReadCompletedBecauseCancel/unix === RUN TestSocketStreamReadCompletedBecauseCancel/tcp ---- PASS: TestSocketStreamReadCompletedBecauseCancel (0.01s) +--- PASS: TestSocketStreamReadCompletedBecauseCancel (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseCancel/unix (0.00s) --- PASS: TestSocketStreamReadCompletedBecauseCancel/tcp (0.00s) PASS -ok github.com/google/mtail/internal/tailer/logstream 3.484s +ok github.com/google/mtail/internal/tailer/logstream 1.131s === RUN TestDoOrTimeoutNeverOK --- PASS: TestDoOrTimeoutNeverOK (0.01s) === RUN TestDoOrTimeoutAlwaysOK @@ -3625,17 +3661,17 @@ === RUN TestDoOrTimeoutAlwaysErr --- PASS: TestDoOrTimeoutAlwaysErr (0.00s) PASS -ok github.com/google/mtail/internal/testutil 1.167s +ok github.com/google/mtail/internal/testutil 1.097s === RUN TestTestWakerWakes --- PASS: TestTestWakerWakes (0.00s) === RUN TestTestWakerTwoWakees ---- PASS: TestTestWakerTwoWakees (0.01s) +--- PASS: TestTestWakerTwoWakees (0.00s) === RUN TestTestWakerTwoWakeups --- PASS: TestTestWakerTwoWakeups (0.00s) === RUN TestTimedWakerWakes --- PASS: TestTimedWakerWakes (0.01s) PASS -ok github.com/google/mtail/internal/waker 1.107s +ok github.com/google/mtail/internal/waker 1.035s 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 @@ -3678,8 +3714,8 @@ dpkg-gencontrol: warning: package mtail: substitution variable ${misc:Static-Built-Using} unused, but is defined dh_md5sums -O--buildsystem=golang -O--builddirectory=/build/reproducible-path/mtail-3.0.0\~rc54\+git0ff5/build dh_builddeb -O--buildsystem=golang -O--builddirectory=/build/reproducible-path/mtail-3.0.0\~rc54\+git0ff5/build -dpkg-deb: building package 'mtail' in '../mtail_3.0.0~rc54+git0ff5-1_amd64.deb'. dpkg-deb: building package 'mtail-dbgsym' in '../mtail-dbgsym_3.0.0~rc54+git0ff5-1_amd64.deb'. +dpkg-deb: building package 'mtail' in '../mtail_3.0.0~rc54+git0ff5-1_amd64.deb'. dpkg-genbuildinfo --build=binary -O../mtail_3.0.0~rc54+git0ff5-1_amd64.buildinfo dpkg-genchanges --build=binary -O../mtail_3.0.0~rc54+git0ff5-1_amd64.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -3687,12 +3723,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/40421/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/40421/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/1089091 and its subdirectories -I: Current time: Sat Jun 1 12:35:45 -12 2024 -I: pbuilder-time-stamp: 1717288545 +I: removing directory /srv/workspace/pbuilder/40421 and its subdirectories +I: Current time: Sat Jul 5 21:03:07 +14 2025 +I: pbuilder-time-stamp: 1751698987