Diff of the two buildlogs: -- --- b1/build.log 2024-05-20 02:46:26.736423760 +0000 +++ b2/build.log 2024-05-20 02:50:43.857487028 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun May 19 14:42:09 -12 2024 -I: pbuilder-time-stamp: 1716172929 +I: Current time: Mon May 20 16:46:38 +14 2024 +I: pbuilder-time-stamp: 1716173198 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -28,52 +28,84 @@ dpkg-source: info: applying 000-fix-32bit-test-failure.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/14172/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/29128/tmp/hooks/D01_modify_environment starting +debug: Running on virt32c. +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 May 20 02:47 /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/29128/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/29128/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='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + 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]="arm-unknown-linux-gnueabihf") + 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=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='e178c727680640a6ab6001768e030911' - 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='14172' - PS1='# ' - PS2='> ' + INVOCATION_ID=cbe95f80e7d54625856ed4cb18b6d4d0 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + 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=29128 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.yTGQ1kYh/pbuilderrc_dDiJ --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.yTGQ1kYh/b1 --logfile b1/build.log golang-github-ovn-org-libovsdb_0.6.0+git20230912.239822f-1.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + 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.yTGQ1kYh/pbuilderrc_dWcj --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.yTGQ1kYh/b2 --logfile b2/build.log golang-github-ovn-org-libovsdb_0.6.0+git20230912.239822f-1.dsc' + SUDO_GID=113 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt64c 6.1.0-21-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-armmp-lpae #1 SMP Debian 6.1.90-1 (2024-05-03) armv7l GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 17 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/14172/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 19 22:28 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/29128/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -209,7 +241,7 @@ Get: 78 http://deb.debian.org/debian unstable/main armhf golang-github-prometheus-procfs-dev all 0.14.0-1 [183 kB] Get: 79 http://deb.debian.org/debian unstable/main armhf golang-github-prometheus-client-golang-dev all 1.19.0-1 [179 kB] Get: 80 http://deb.debian.org/debian unstable/main armhf golang-github-mwitkow-go-conntrack-dev all 0.0~git20190716.2f06839-3 [14.6 kB] -Fetched 80.6 MB in 4s (22.1 MB/s) +Fetched 80.6 MB in 2s (50.5 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (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 ... 19463 files and directories currently installed.) @@ -544,7 +576,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-ovn-org-libovsdb-0.6.0+git20230912.239822f/ && 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 > ../golang-github-ovn-org-libovsdb_0.6.0+git20230912.239822f-1_source.changes +I: user script /srv/workspace/pbuilder/29128/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/29128/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-ovn-org-libovsdb-0.6.0+git20230912.239822f/ && 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 > ../golang-github-ovn-org-libovsdb_0.6.0+git20230912.239822f-1_source.changes dpkg-buildpackage: info: source package golang-github-ovn-org-libovsdb dpkg-buildpackage: info: source version 0.6.0+git20230912.239822f-1 dpkg-buildpackage: info: source distribution unstable @@ -566,19 +602,19 @@ rm _build/src/github.com/ovn-org/libovsdb/modelgen/table_test.go make[1]: Leaving directory '/build/reproducible-path/golang-github-ovn-org-libovsdb-0.6.0+git20230912.239822f' dh_auto_build -O--builddirectory=_build -O--buildsystem=golang - cd _build && go install -trimpath -v -p 3 github.com/ovn-org/libovsdb/cache github.com/ovn-org/libovsdb/client github.com/ovn-org/libovsdb/database github.com/ovn-org/libovsdb/mapper github.com/ovn-org/libovsdb/model github.com/ovn-org/libovsdb/modelgen github.com/ovn-org/libovsdb/ovsdb github.com/ovn-org/libovsdb/ovsdb/serverdb github.com/ovn-org/libovsdb/server github.com/ovn-org/libovsdb/test github.com/ovn-org/libovsdb/updates -internal/goarch + cd _build && go install -trimpath -v -p 4 github.com/ovn-org/libovsdb/cache github.com/ovn-org/libovsdb/client github.com/ovn-org/libovsdb/database github.com/ovn-org/libovsdb/mapper github.com/ovn-org/libovsdb/model github.com/ovn-org/libovsdb/modelgen github.com/ovn-org/libovsdb/ovsdb github.com/ovn-org/libovsdb/ovsdb/serverdb github.com/ovn-org/libovsdb/server github.com/ovn-org/libovsdb/test github.com/ovn-org/libovsdb/updates internal/unsafeheader +internal/goarch +internal/godebugs internal/cpu internal/coverage/rtcov internal/abi internal/chacha8rand -internal/bytealg -internal/godebugs internal/goexperiment internal/goos -runtime/internal/atomic runtime/internal/math +internal/bytealg +runtime/internal/atomic runtime/internal/sys runtime/internal/syscall internal/race @@ -586,137 +622,137 @@ unicode unicode/utf8 math/bits -runtime -math crypto/internal/alias internal/itoa crypto/internal/boring/sig +math encoding cmp slices log/internal unicode/utf16 +runtime container/list vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace vendor/golang.org/x/crypto/internal/alias google.golang.org/protobuf/internal/flags google.golang.org/protobuf/internal/set -crypto/subtle internal/reflectlite +crypto/subtle sync runtime/metrics internal/testlog -internal/bisect internal/singleflight +internal/bisect +github.com/cenkalti/hub +google.golang.org/protobuf/internal/pragma runtime/cgo +internal/godebug errors sort io -bytes strconv +internal/oserror +bytes +syscall hash -strings crypto reflect +strings bufio -internal/oserror -syscall internal/safefilepath path internal/saferio regexp/syntax internal/syscall/unix time -encoding/binary -internal/fmtsort internal/syscall/execenv -crypto/cipher -encoding/base64 +crypto/internal/nistec/fiat regexp -crypto/internal/boring -crypto/sha256 internal/poll +encoding/binary +internal/fmtsort io/fs context -crypto/aes +crypto/cipher os -crypto/des github.com/go-logr/logr +crypto/internal/boring +encoding/base64 +crypto/sha256 +crypto/aes +crypto/des crypto/internal/edwards25519/field -crypto/internal/nistec/fiat crypto/internal/randutil -internal/godebug math/rand crypto/sha512 +crypto/internal/edwards25519 +crypto/hmac +crypto/md5 fmt path/filepath io/ioutil -crypto/internal/edwards25519 +crypto/internal/nistec +crypto/rc4 +crypto/sha1 +encoding/pem +vendor/golang.org/x/net/dns/dnsmessage +internal/intern encoding/gob encoding/hex github.com/go-logr/logr/funcr log encoding/json github.com/go-logr/stdr -crypto/internal/nistec -math/big crypto/ecdh -crypto/hmac +math/big +net/netip +net/url +net github.com/ovn-org/libovsdb/ovsdb -crypto/md5 -crypto/rc4 -crypto/sha1 -encoding/pem -vendor/golang.org/x/net/dns/dnsmessage +vendor/golang.org/x/crypto/chacha20 +vendor/golang.org/x/crypto/internal/poly1305 +vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/crypto/hkdf +github.com/cenkalti/backoff github.com/ovn-org/libovsdb/mapper -crypto/elliptic +database/sql/driver github.com/ovn-org/libovsdb/model -crypto/internal/bigmod +compress/flate +crypto/elliptic github.com/ovn-org/libovsdb/updates +crypto/internal/bigmod crypto/internal/boring/bbig encoding/asn1 crypto/rand -crypto/ed25519 github.com/ovn-org/libovsdb/cache -crypto/rsa +crypto/ed25519 vendor/golang.org/x/crypto/cryptobyte +crypto/rsa crypto/dsa crypto/x509/pkix -internal/intern -net/url -net/netip -crypto/ecdsa -vendor/golang.org/x/crypto/chacha20 -vendor/golang.org/x/crypto/internal/poly1305 -vendor/golang.org/x/crypto/hkdf -net -vendor/golang.org/x/crypto/chacha20poly1305 -github.com/cenkalti/backoff -github.com/cenkalti/hub -database/sql/driver github.com/ovn-org/libovsdb/ovsdb/serverdb -compress/flate +crypto/ecdsa hash/crc32 vendor/golang.org/x/text/transform -vendor/golang.org/x/text/unicode/bidi compress/gzip +vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/text/secure/bidirule vendor/golang.org/x/net/http2/hpack mime +vendor/golang.org/x/text/secure/bidirule mime/quotedprintable -vendor/golang.org/x/net/idna net/http/internal net/http/internal/ascii github.com/beorn7/perks/quantile github.com/cespare/xxhash hash/fnv -google.golang.org/protobuf/internal/pragma embed google.golang.org/protobuf/internal/detrand google.golang.org/protobuf/internal/errors google.golang.org/protobuf/internal/editiondefaults +vendor/golang.org/x/net/idna go/token google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/reflect/protoreflect @@ -725,80 +761,80 @@ github.com/prometheus/procfs/internal/fs github.com/prometheus/procfs/internal/util golang.org/x/sys/unix +runtime/debug google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/strs -google.golang.org/protobuf/internal/encoding/text -google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/genid +google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/internal/order google.golang.org/protobuf/reflect/protoregistry +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/runtime/protoiface +github.com/davecgh/go-spew/spew google.golang.org/protobuf/proto -runtime/debug +github.com/pmezard/go-difflib/difflib +gopkg.in/yaml.v3 google.golang.org/protobuf/internal/filedesc google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/encoding/protodelim -github.com/davecgh/go-spew/spew +google.golang.org/protobuf/internal/encoding/tag crypto/x509 github.com/cenkalti/rpc2 github.com/google/uuid -github.com/cenkalti/rpc2/jsonrpc net/textproto -vendor/golang.org/x/net/http/httpproxy -google.golang.org/protobuf/internal/encoding/tag +github.com/cenkalti/rpc2/jsonrpc vendor/golang.org/x/net/http/httpguts +vendor/golang.org/x/net/http/httpproxy mime/multipart google.golang.org/protobuf/internal/impl -crypto/tls github.com/prometheus/procfs -github.com/pmezard/go-difflib/difflib -gopkg.in/yaml.v3 -net/http/httptrace -net/http -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/runtime/protoimpl -google.golang.org/protobuf/types/known/timestamppb -github.com/prometheus/client_model/go flag -github.com/prometheus/common/model net/http/internal/testcert internal/sysinfo +crypto/tls runtime/trace testing -github.com/prometheus/client_golang/prometheus/internal github.com/ovn-org/libovsdb/database go/scanner +go/ast go/build/constraint go/doc/comment -go/ast text/tabwriter -golang.org/x/text/internal/tag -golang.org/x/text/internal/language go/internal/typeparams -go/parser go/printer -golang.org/x/text/internal/language/compact -golang.org/x/text/language +go/parser +google.golang.org/protobuf/internal/filetype +golang.org/x/text/internal/tag +golang.org/x/text/internal/language +net/http/httptrace +google.golang.org/protobuf/runtime/protoimpl go/format +google.golang.org/protobuf/types/known/timestamppb +net/http golang.org/x/text/transform -golang.org/x/text/internal -text/template/parse +github.com/prometheus/client_model/go golang.org/x/text/unicode/norm +github.com/prometheus/common/model +golang.org/x/text/internal/language/compact +golang.org/x/text/language +text/template/parse +github.com/prometheus/client_golang/prometheus/internal +golang.org/x/text/internal +github.com/ovn-org/libovsdb/server +golang.org/x/text/cases +github.com/ovn-org/libovsdb/test +text/template +github.com/ovn-org/libovsdb/modelgen expvar -github.com/prometheus/common/expfmt net/http/httptest -golang.org/x/text/cases +github.com/prometheus/common/expfmt github.com/stretchr/testify/assert github.com/prometheus/client_golang/prometheus -text/template -github.com/ovn-org/libovsdb/modelgen -github.com/ovn-org/libovsdb/server -github.com/ovn-org/libovsdb/test github.com/ovn-org/libovsdb/client dh_auto_test -O--builddirectory=_build -O--buildsystem=golang - cd _build && go test -vet=off -v -p 3 github.com/ovn-org/libovsdb/cache github.com/ovn-org/libovsdb/client github.com/ovn-org/libovsdb/database github.com/ovn-org/libovsdb/mapper github.com/ovn-org/libovsdb/model github.com/ovn-org/libovsdb/modelgen github.com/ovn-org/libovsdb/ovsdb github.com/ovn-org/libovsdb/ovsdb/serverdb github.com/ovn-org/libovsdb/server github.com/ovn-org/libovsdb/test github.com/ovn-org/libovsdb/updates + cd _build && go test -vet=off -v -p 4 github.com/ovn-org/libovsdb/cache github.com/ovn-org/libovsdb/client github.com/ovn-org/libovsdb/database github.com/ovn-org/libovsdb/mapper github.com/ovn-org/libovsdb/model github.com/ovn-org/libovsdb/modelgen github.com/ovn-org/libovsdb/ovsdb github.com/ovn-org/libovsdb/ovsdb/serverdb github.com/ovn-org/libovsdb/server github.com/ovn-org/libovsdb/test github.com/ovn-org/libovsdb/updates === RUN TestRowCache_Row === RUN TestRowCache_Row/returns_a_row_that_exists === RUN TestRowCache_Row/returns_a_nil_for_a_row_that_does_not_exist @@ -901,7 +937,7 @@ === RUN TestRowCacheUpdateMultiClientIndex/update_both_index_columns === RUN TestRowCacheUpdateMultiClientIndex/update_unique_index_to_existing_index === RUN TestRowCacheUpdateMultiClientIndex/update_multi_index_to_different_index ---- PASS: TestRowCacheUpdateMultiClientIndex (0.00s) +--- PASS: TestRowCacheUpdateMultiClientIndex (0.01s) --- PASS: TestRowCacheUpdateMultiClientIndex/error_if_row_does_not_exist (0.00s) --- PASS: TestRowCacheUpdateMultiClientIndex/update_non-index (0.00s) --- PASS: TestRowCacheUpdateMultiClientIndex/update_one_index_column (0.00s) @@ -1092,7 +1128,8 @@ --- PASS: TestTableCache_ApplyModelUpdates/update_noop (0.00s) --- PASS: TestTableCache_ApplyModelUpdates/delete (0.00s) PASS -ok github.com/ovn-org/libovsdb/cache 0.078s +ok github.com/ovn-org/libovsdb/cache 0.083s +? github.com/ovn-org/libovsdb/ovsdb/serverdb [no test files] === RUN TestAPIListSimple === RUN TestAPIListSimple/ApiList:_full === RUN TestAPIListSimple/ApiList:_single @@ -1219,7 +1256,7 @@ === RUN TestAPIUpdate/ApiUpdate:_select_multiple_by_predicate_change_multiple_field === RUN TestAPIUpdate/ApiUpdate:_multiple_different_selected_models_must_fail === RUN TestAPIUpdate/ApiUpdate:_fails_if_conditional_is_an_error ---- PASS: TestAPIUpdate (0.01s) +--- PASS: TestAPIUpdate (0.00s) --- PASS: TestAPIUpdate/ApiUpdate:_select_by_UUID_change_multiple_field (0.00s) --- PASS: TestAPIUpdate/ApiUpdate:_select_by_UUID_change_multiple_field_with_nil_pointer/empty_set (0.00s) --- PASS: TestAPIUpdate/ApiUpdate:_select_by_UUID_with_no_fields_does_not_change_multiple_field_with_nil_pointer/empty_set (0.00s) @@ -1282,11 +1319,11 @@ --- PASS: TestOperationWhenNeverConnected/monitor/monitor_all (0.00s) --- PASS: TestOperationWhenNeverConnected/monitor_cancel (0.00s) === RUN TestTransactionLogger -2024/05/20 02:45:42 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="c472a682-9d64-4d9f-87ac-c94a43d83b7d" "model"={"UUID":"c472a682-9d64-4d9f-87ac-c94a43d83b7d","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"foo","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} -2024/05/20 02:45:42 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="c472a682-9d64-4d9f-87ac-c94a43d83b7d" "model"={"UUID":"c472a682-9d64-4d9f-87ac-c94a43d83b7d","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"foo","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} -2024/05/20 02:45:42 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="e4049aec-f333-46eb-b823-c804e96b5a0a" "model"={"UUID":"e4049aec-f333-46eb-b823-c804e96b5a0a","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"baz":"quux","waldo":"fred","foo":"bar"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"bar","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} -2024/05/20 02:45:42 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="e4049aec-f333-46eb-b823-c804e96b5a0a" "model"={"UUID":"e4049aec-f333-46eb-b823-c804e96b5a0a","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"waldo":"fred","foo":"bar","baz":"quux"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"bar","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} ---- PASS: TestTransactionLogger (0.03s) +2024/05/20 02:50:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="077b1fe8-4937-4fc5-bc2b-5c962fee03f6" "model"={"UUID":"077b1fe8-4937-4fc5-bc2b-5c962fee03f6","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"foo","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} +2024/05/20 02:50:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="077b1fe8-4937-4fc5-bc2b-5c962fee03f6" "model"={"UUID":"077b1fe8-4937-4fc5-bc2b-5c962fee03f6","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"foo","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} +2024/05/20 02:50:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="67efa112-7983-460e-935a-2b2ad95aa488" "model"={"UUID":"67efa112-7983-460e-935a-2b2ad95aa488","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"bar","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} +2024/05/20 02:50:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="67efa112-7983-460e-935a-2b2ad95aa488" "model"={"UUID":"67efa112-7983-460e-935a-2b2ad95aa488","AutoAttach":null,"Controller":[],"DatapathID":null,"DatapathType":"","DatapathVersion":"","ExternalIDs":{"foo":"bar","baz":"quux","waldo":"fred"},"FailMode":null,"FloodVLANs":[],"FlowTables":{},"IPFIX":null,"McastSnoopingEnable":false,"Mirrors":[],"Name":"bar","Netflow":null,"OtherConfig":{},"Ports":[],"Protocols":[],"RSTPEnable":false,"RSTPStatus":{},"Sflow":null,"Status":{},"STPEnable":false} +--- PASS: TestTransactionLogger (0.02s) === RUN TestOperationWhenNotConnected === RUN TestOperationWhenNotConnected/echo === RUN TestOperationWhenNotConnected/transact @@ -1300,105 +1337,105 @@ === RUN TestSetOption --- PASS: TestSetOption (0.00s) === RUN TestClientInactiveCheck -2024/05/20 02:45:42 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" -2024/05/20 02:45:42 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" "sid"="" -2024/05/20 02:45:44 libovsdb: "caller"={"file":"client.go","line":1253} "msg"="server echo reply error" "error"="no echo reply" "database"="_Server" -2024/05/20 02:45:44 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" -2024/05/20 02:45:44 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" -2024/05/20 02:45:44 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" "sid"="" -2024/05/20 02:45:44 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="_Server" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":1253} "msg"="server echo reply error" "error"="no echo reply" "database"="_Server" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1218.sock" "sid"="" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="_Server" ---- PASS: TestClientInactiveCheck (4.03s) +2024/05/20 02:50:03 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" +2024/05/20 02:50:03 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" "sid"="" +2024/05/20 02:50:05 libovsdb: "caller"={"file":"client.go","line":1253} "msg"="server echo reply error" "error"="no echo reply" "database"="_Server" +2024/05/20 02:50:05 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" +2024/05/20 02:50:05 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" +2024/05/20 02:50:05 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" "sid"="" +2024/05/20 02:50:05 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="_Server" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":1253} "msg"="server echo reply error" "error"="no echo reply" "database"="_Server" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7446.sock" "sid"="" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="_Server" +--- PASS: TestClientInactiveCheck (4.04s) === RUN TestClientReconnectLeaderOnly -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1105.sock" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-1105.sock" "sid"="" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:dcd48f11}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:2db900b4-ed16-4f60-a6ab-b2a6a9718c0a UUIDName:}]" -2024/05/20 02:45:46 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "model"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:46 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "model"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8830.sock" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8830.sock" "sid"="" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:false model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:b3f67855}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:83fe3622-ba2c-47b3-bd59-a1e56829ac26 UUIDName:}]" -2024/05/20 02:45:46 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "model"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:46 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "model"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:46 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" "sid"="dcd48f11" -2024/05/20 02:45:46 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" -2024/05/20 02:45:46 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "model"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:true] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {83fe3622-ba2c-47b3-bd59-a1e56829ac26}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:48 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "old"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} "new"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:48 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "old"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} "new"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:false] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {2db900b4-ed16-4f60-a6ab-b2a6a9718c0a}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:48 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "old"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} "new"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:48 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" -2024/05/20 02:45:48 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "old"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} "new"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":1150} "level"=3 "msg"="endpoint lost leader, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" "sid"="dcd48f11" -2024/05/20 02:45:48 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "old"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} "new"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8830.sock" -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8830.sock" -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8830.sock" "sid"="b3f67855" -2024/05/20 02:45:48 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" -2024/05/20 02:45:48 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" -2024/05/20 02:45:48 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "model"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:true] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {2db900b4-ed16-4f60-a6ab-b2a6a9718c0a}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:50 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "old"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} "new"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:50 client error handling update2 rpc: connection is shut down -2024/05/20 02:45:50 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "old"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} "new"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:false] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {83fe3622-ba2c-47b3-bd59-a1e56829ac26}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:50 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "old"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} "new"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:50 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" -2024/05/20 02:45:50 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "old"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} "new"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":1150} "level"=3 "msg"="endpoint lost leader, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8830.sock" "sid"="b3f67855" -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" -2024/05/20 02:45:50 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="83fe3622-ba2c-47b3-bd59-a1e56829ac26" "old"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} "new"={"UUID":"83fe3622-ba2c-47b3-bd59-a1e56829ac26","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"b3f67855"} -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1105.sock" "sid"="dcd48f11" -2024/05/20 02:45:50 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" -2024/05/20 02:45:50 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" -2024/05/20 02:45:50 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="2db900b4-ed16-4f60-a6ab-b2a6a9718c0a" "model"={"UUID":"2db900b4-ed16-4f60-a6ab-b2a6a9718c0a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"dcd48f11"} ---- PASS: TestClientReconnectLeaderOnly (6.10s) +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-3022.sock" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-3022.sock" "sid"="" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:cd467de6}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:0f8c46bd-de72-49d0-8f71-08f293a9d303 UUIDName:}]" +2024/05/20 02:50:07 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "model"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:07 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "model"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8828.sock" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8828.sock" "sid"="" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:false model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:846ad6c}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:c35d2db6-cba7-4868-b595-89469f94a17a UUIDName:}]" +2024/05/20 02:50:07 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "model"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:07 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "model"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:07 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" "sid"="cd467de6" +2024/05/20 02:50:07 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" +2024/05/20 02:50:07 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "model"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:true] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {c35d2db6-cba7-4868-b595-89469f94a17a}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:09 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "old"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} "new"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:09 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "old"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} "new"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:false] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {0f8c46bd-de72-49d0-8f71-08f293a9d303}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:09 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "old"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} "new"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:09 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" +2024/05/20 02:50:09 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "old"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} "new"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":1150} "level"=3 "msg"="endpoint lost leader, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" "sid"="cd467de6" +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8828.sock" +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8828.sock" +2024/05/20 02:50:09 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "old"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} "new"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8828.sock" "sid"="846ad6c" +2024/05/20 02:50:09 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" +2024/05/20 02:50:09 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" +2024/05/20 02:50:09 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "model"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:true] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {0f8c46bd-de72-49d0-8f71-08f293a9d303}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:11 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "old"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} "new"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:11 client error handling update2 rpc: connection is shut down +2024/05/20 02:50:11 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "old"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} "new"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:update Table:Database Row:map[leader:false] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {c35d2db6-cba7-4868-b595-89469f94a17a}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:11 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "old"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} "new"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:11 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" +2024/05/20 02:50:11 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "old"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} "new"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":1150} "level"=3 "msg"="endpoint lost leader, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8828.sock" "sid"="846ad6c" +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" +2024/05/20 02:50:11 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Database" "uuid"="c35d2db6-cba7-4868-b595-89469f94a17a" "old"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} "new"={"UUID":"c35d2db6-cba7-4868-b595-89469f94a17a","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"846ad6c"} +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-3022.sock" "sid"="cd467de6" +2024/05/20 02:50:11 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" +2024/05/20 02:50:11 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" +2024/05/20 02:50:11 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="0f8c46bd-de72-49d0-8f71-08f293a9d303" "model"={"UUID":"0f8c46bd-de72-49d0-8f71-08f293a9d303","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cd467de6"} +--- PASS: TestClientReconnectLeaderOnly (6.15s) === RUN TestNewMonitorRequest --- PASS: TestNewMonitorRequest (0.00s) === RUN TestUpdateEndpoints -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8093.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8093.sock" "sid"="" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:502b1622}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:4784e362-ff20-4109-a8b0-80f208f461f8 UUIDName:}]" -2024/05/20 02:45:52 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="4784e362-ff20-4109-a8b0-80f208f461f8" "model"={"UUID":"4784e362-ff20-4109-a8b0-80f208f461f8","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"502b1622"} -2024/05/20 02:45:52 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="4784e362-ff20-4109-a8b0-80f208f461f8" "model"={"UUID":"4784e362-ff20-4109-a8b0-80f208f461f8","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"502b1622"} -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-2928.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-2928.sock" "sid"="" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:false model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:305793ff}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:0c452d76-7f68-42bd-a478-c1cb5d5c53f1 UUIDName:}]" -2024/05/20 02:45:52 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="0c452d76-7f68-42bd-a478-c1cb5d5c53f1" "model"={"UUID":"0c452d76-7f68-42bd-a478-c1cb5d5c53f1","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"305793ff"} -2024/05/20 02:45:52 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="0c452d76-7f68-42bd-a478-c1cb5d5c53f1" "model"={"UUID":"0c452d76-7f68-42bd-a478-c1cb5d5c53f1","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"305793ff"} -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8293.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-8293.sock" "sid"="" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:cafdf66a}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:c8a844be-85c5-470c-92f5-2214a85a26ae UUIDName:}]" -2024/05/20 02:45:52 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="c8a844be-85c5-470c-92f5-2214a85a26ae" "model"={"UUID":"c8a844be-85c5-470c-92f5-2214a85a26ae","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cafdf66a"} -2024/05/20 02:45:52 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="c8a844be-85c5-470c-92f5-2214a85a26ae" "model"={"UUID":"c8a844be-85c5-470c-92f5-2214a85a26ae","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cafdf66a"} -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8093.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8093.sock" "sid"="502b1622" -2024/05/20 02:45:52 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="4784e362-ff20-4109-a8b0-80f208f461f8" -2024/05/20 02:45:52 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="4784e362-ff20-4109-a8b0-80f208f461f8" "model"={"UUID":"4784e362-ff20-4109-a8b0-80f208f461f8","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"502b1622"} -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-8093.sock"] -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-2928.sock","unix:/tmp/ovsdb-8093.sock"] -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-2928.sock","unix:/tmp/ovsdb-8293.sock"] -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2928.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2928.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8293.sock" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8293.sock" "sid"="cafdf66a" -2024/05/20 02:45:52 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" -2024/05/20 02:45:52 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="c8a844be-85c5-470c-92f5-2214a85a26ae" -2024/05/20 02:45:52 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="c8a844be-85c5-470c-92f5-2214a85a26ae" "model"={"UUID":"c8a844be-85c5-470c-92f5-2214a85a26ae","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"cafdf66a"} ---- PASS: TestUpdateEndpoints (0.16s) +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-5986.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-5986.sock" "sid"="" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:24fa20c}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:1bcc5717-5564-48c0-8a78-8cfe35039471 UUIDName:}]" +2024/05/20 02:50:13 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="1bcc5717-5564-48c0-8a78-8cfe35039471" "model"={"UUID":"1bcc5717-5564-48c0-8a78-8cfe35039471","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"24fa20c"} +2024/05/20 02:50:13 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="1bcc5717-5564-48c0-8a78-8cfe35039471" "model"={"UUID":"1bcc5717-5564-48c0-8a78-8cfe35039471","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"24fa20c"} +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-5384.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-5384.sock" "sid"="" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:false model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:d4504387}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:b97d2d40-ded8-4cd4-97da-4277d98f588c UUIDName:}]" +2024/05/20 02:50:13 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="b97d2d40-ded8-4cd4-97da-4277d98f588c" "model"={"UUID":"b97d2d40-ded8-4cd4-97da-4277d98f588c","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"d4504387"} +2024/05/20 02:50:13 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="b97d2d40-ded8-4cd4-97da-4277d98f588c" "model"={"UUID":"b97d2d40-ded8-4cd4-97da-4277d98f588c","Cid":null,"Connected":true,"Index":null,"Leader":false,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"d4504387"} +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7284.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="_Server" "endpoint"="unix:/tmp/ovsdb-7284.sock" "sid"="" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="_Server" "database"="_Server" "operations"="[{Op:insert Table:Database Row:map[connected:true leader:true model:clustered name:Open_vSwitch sid:{GoSet:[{GoUUID:677f09c7}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID:9f0561de-8ef0-4d83-8db2-f969f64cba9c UUIDName:}]" +2024/05/20 02:50:13 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="9f0561de-8ef0-4d83-8db2-f969f64cba9c" "model"={"UUID":"9f0561de-8ef0-4d83-8db2-f969f64cba9c","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"677f09c7"} +2024/05/20 02:50:13 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Database" "uuid"="9f0561de-8ef0-4d83-8db2-f969f64cba9c" "model"={"UUID":"9f0561de-8ef0-4d83-8db2-f969f64cba9c","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"677f09c7"} +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5986.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5986.sock" "sid"="24fa20c" +2024/05/20 02:50:13 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="1bcc5717-5564-48c0-8a78-8cfe35039471" +2024/05/20 02:50:13 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="1bcc5717-5564-48c0-8a78-8cfe35039471" "model"={"UUID":"1bcc5717-5564-48c0-8a78-8cfe35039471","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"24fa20c"} +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-5986.sock"] +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-5384.sock","unix:/tmp/ovsdb-5986.sock"] +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":536} "level"=3 "msg"="update endpoints" "database"="Open_vSwitch" "endpoints"=["unix:/tmp/ovsdb-5384.sock","unix:/tmp/ovsdb-7284.sock"] +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":1306} "level"=3 "msg"="connection lost, reconnecting" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5384.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5384.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-7284.sock" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="_Server" "operations"="[{Op:select Table:Database Row:map[] Rows:[] Columns:[name model leader sid] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-7284.sock" "sid"="677f09c7" +2024/05/20 02:50:13 libovsdb: "caller"={"file":"client.go","line":285} "level"=3 "msg"="reconnected - restarting monitors" "database"="Open_vSwitch" +2024/05/20 02:50:13 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Database" "uuid"="9f0561de-8ef0-4d83-8db2-f969f64cba9c" +2024/05/20 02:50:13 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Database" "uuid"="9f0561de-8ef0-4d83-8db2-f969f64cba9c" "model"={"UUID":"9f0561de-8ef0-4d83-8db2-f969f64cba9c","Cid":null,"Connected":true,"Index":null,"Leader":true,"Model":"clustered","Name":"Open_vSwitch","Schema":null,"Sid":"677f09c7"} +--- PASS: TestUpdateEndpoints (0.26s) === RUN TestEqualityConditional === RUN TestEqualityConditional/Equality_Conditional:_by_uuid === RUN TestEqualityConditional/Equality_Conditional:_by_uuids @@ -1476,7 +1513,7 @@ === RUN TestWithReconnect --- PASS: TestWithReconnect (0.00s) PASS -ok github.com/ovn-org/libovsdb/client 10.392s +ok github.com/ovn-org/libovsdb/client 10.581s === RUN TestWaitOpEquals --- PASS: TestWaitOpEquals (0.40s) === RUN TestWaitOpNotEquals @@ -1513,7 +1550,7 @@ --- PASS: TestCheckIndexes/Updating_an_index_to_an_old_index_that_is_updated_in_the_same_transaction_should_succeed (0.00s) --- PASS: TestCheckIndexes/Updating_an_index_to_a_old_index_that_is_deleted_in_the_same_transaction_should_succeed (0.00s) PASS -ok github.com/ovn-org/libovsdb/database 0.631s +ok github.com/ovn-org/libovsdb/database 0.639s === RUN TestNewMapperInfo === RUN TestNewMapperInfo/NewMapper_no_orm --- PASS: TestNewMapperInfo (0.00s) @@ -1659,7 +1696,7 @@ --- PASS: TestMapperMutation/newMutationDelete_key_value_pairs_from_map_ (0.00s) --- PASS: TestMapperMutation/newMutationInsert_elements_in_map_ (0.00s) PASS -ok github.com/ovn-org/libovsdb/mapper 0.022s +ok github.com/ovn-org/libovsdb/mapper 0.033s === RUN TestClientDBModel === RUN TestClientDBModel/TestNewModel_valid === RUN TestClientDBModel/TestNewModel_valid_multiple @@ -1703,7 +1740,7 @@ === RUN TestEqualViaComparable --- PASS: TestEqualViaComparable (0.00s) PASS -ok github.com/ovn-org/libovsdb/model 0.012s +ok github.com/ovn-org/libovsdb/model 0.018s === RUN TestDbModelTemplate === RUN TestDbModelTemplate/normal --- PASS: TestDbModelTemplate (0.00s) @@ -1721,8 +1758,7 @@ --- PASS: Test_newOptions/With_DryRun (0.00s) --- PASS: Test_newOptions/Without_DryRun (0.00s) PASS -ok github.com/ovn-org/libovsdb/modelgen 0.011s -? github.com/ovn-org/libovsdb/ovsdb/serverdb [no test files] +ok github.com/ovn-org/libovsdb/modelgen 0.027s === RUN TestOvsToNativeAndNativeToOvs === RUN TestOvsToNativeAndNativeToOvs/String === RUN TestOvsToNativeAndNativeToOvs/Float @@ -1747,7 +1783,7 @@ === RUN TestOvsToNativeAndNativeToOvs/UUID_set_with_min_0_max_1 === RUN TestOvsToNativeAndNativeToOvs/null_UUID_set_with_min_0_max_1 === RUN TestOvsToNativeAndNativeToOvs/A_string_with_min_0_max_1 ---- PASS: TestOvsToNativeAndNativeToOvs (0.01s) +--- PASS: TestOvsToNativeAndNativeToOvs (0.00s) --- PASS: TestOvsToNativeAndNativeToOvs/String (0.00s) --- PASS: TestOvsToNativeAndNativeToOvs/Float (0.00s) --- PASS: TestOvsToNativeAndNativeToOvs/Integers_with_float_ovs_type (0.00s) @@ -2476,158 +2512,158 @@ === RUN TestMonitorFilter2/not_filtered === RUN TestMonitorFilter2/removed_table === RUN TestMonitorFilter2/removed_column ---- PASS: TestMonitorFilter2 (0.00s) +--- PASS: TestMonitorFilter2 (0.01s) --- PASS: TestMonitorFilter2/not_filtered (0.00s) --- PASS: TestMonitorFilter2/removed_table (0.00s) --- PASS: TestMonitorFilter2/removed_column (0.00s) === RUN TestClientServerEcho -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2425.sock" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2425.sock" "sid"="" -2024/05/20 02:46:03 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-2425.sock: use of closed network connection" +2024/05/20 02:50:14 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5012.sock" +2024/05/20 02:50:14 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5012.sock" "sid"="" +2024/05/20 02:50:14 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-5012.sock: use of closed network connection" --- PASS: TestClientServerEcho (0.02s) === RUN TestClientServerInsert -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9634.sock" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9634.sock" "sid"="" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[wallace]} datapath_type:bar external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="538b4359-e2a1-48f1-8097-772807839563" "model"={"UUID":"538b4359-e2a1-48f1-8097-772807839563","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="538b4359-e2a1-48f1-8097-772807839563" -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="538b4359-e2a1-48f1-8097-772807839563" "model"={"UUID":"538b4359-e2a1-48f1-8097-772807839563","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="538b4359-e2a1-48f1-8097-772807839563" "model"={"UUID":"538b4359-e2a1-48f1-8097-772807839563","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-9634.sock: use of closed network connection" ---- PASS: TestClientServerInsert (0.52s) +2024/05/20 02:50:14 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-6545.sock" +2024/05/20 02:50:14 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-6545.sock" "sid"="" +2024/05/20 02:50:14 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[wallace]} datapath_type:bar external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:14 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="890093a1-6321-4290-9212-9ece804f3f52" "model"={"UUID":"890093a1-6321-4290-9212-9ece804f3f52","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:14 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="890093a1-6321-4290-9212-9ece804f3f52" +2024/05/20 02:50:14 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="890093a1-6321-4290-9212-9ece804f3f52" "model"={"UUID":"890093a1-6321-4290-9212-9ece804f3f52","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:14 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="890093a1-6321-4290-9212-9ece804f3f52" "model"={"UUID":"890093a1-6321-4290-9212-9ece804f3f52","Name":"foo","DatapathType":"bar","DatapathID":"wallace","OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-6545.sock: use of closed network connection" +--- PASS: TestClientServerInsert (0.53s) === RUN TestClientServerMonitor -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5984.sock" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5984.sock" "sid"="" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Open_vSwitch Row:map[] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:ovs}]" -2024/05/20 02:46:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "model"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} -2024/05/20 02:46:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "model"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "model"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:foo} {Op:mutate Table:Open_vSwitch Row:map[] Rows:[] Columns:[] Mutations:[{Column:bridges Mutator:insert Value:{GoSet:[{GoUUID:foo}]}}] Timeout: Where:[where column _uuid == {b094600b-aa5c-4e6b-9fe7-debb990005eb}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="5d6a9897-65db-4695-837a-4d970645fdc4" "model"={"UUID":"5d6a9897-65db-4695-837a-4d970645fdc4","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "old"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} "new"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":["5d6a9897-65db-4695-837a-4d970645fdc4"]} -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "old"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} "new"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":["5d6a9897-65db-4695-837a-4d970645fdc4"]} -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="5d6a9897-65db-4695-837a-4d970645fdc4" -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="5d6a9897-65db-4695-837a-4d970645fdc4" "model"={"UUID":"5d6a9897-65db-4695-837a-4d970645fdc4","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="5d6a9897-65db-4695-837a-4d970645fdc4" "model"={"UUID":"5d6a9897-65db-4695-837a-4d970645fdc4","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Open_vSwitch" "uuid"="b094600b-aa5c-4e6b-9fe7-debb990005eb" "old"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":[]} "new"={"UUID":"b094600b-aa5c-4e6b-9fe7-debb990005eb","Bridges":["5d6a9897-65db-4695-837a-4d970645fdc4"]} -2024/05/20 02:46:03 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-5984.sock: use of closed network connection" ---- PASS: TestClientServerMonitor (0.05s) +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8498.sock" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8498.sock" "sid"="" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Open_vSwitch Row:map[] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:ovs}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "model"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "model"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "model"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:foo} {Op:mutate Table:Open_vSwitch Row:map[] Rows:[] Columns:[] Mutations:[{Column:bridges Mutator:insert Value:{GoSet:[{GoUUID:foo}]}}] Timeout: Where:[where column _uuid == {d49adddc-8286-45d1-b7c3-cff50d1c71be}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="3f17cf56-2e38-4439-8918-b3be493e270b" "model"={"UUID":"3f17cf56-2e38-4439-8918-b3be493e270b","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "old"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} "new"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":["3f17cf56-2e38-4439-8918-b3be493e270b"]} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "old"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} "new"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":["3f17cf56-2e38-4439-8918-b3be493e270b"]} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3f17cf56-2e38-4439-8918-b3be493e270b" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3f17cf56-2e38-4439-8918-b3be493e270b" "model"={"UUID":"3f17cf56-2e38-4439-8918-b3be493e270b","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="3f17cf56-2e38-4439-8918-b3be493e270b" "model"={"UUID":"3f17cf56-2e38-4439-8918-b3be493e270b","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Open_vSwitch" "uuid"="d49adddc-8286-45d1-b7c3-cff50d1c71be" "old"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":[]} "new"={"UUID":"d49adddc-8286-45d1-b7c3-cff50d1c71be","Bridges":["3f17cf56-2e38-4439-8918-b3be493e270b"]} +2024/05/20 02:50:15 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-8498.sock: use of closed network connection" +--- PASS: TestClientServerMonitor (0.07s) === RUN TestClientServerInsertAndDelete -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2503.sock" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-2503.sock" "sid"="" -2024/05/20 02:46:03 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:03 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" -2024/05/20 02:46:03 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:03 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:delete Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {894a0b0c-0acd-4615-a5e5-27b8f51f03c0}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "table"="Bridge" "uuid"="894a0b0c-0acd-4615-a5e5-27b8f51f03c0" "model"={"UUID":"894a0b0c-0acd-4615-a5e5-27b8f51f03c0","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-2503.sock: use of closed network connection" ---- PASS: TestClientServerInsertAndDelete (0.52s) +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8471.sock" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8471.sock" "sid"="" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:delete Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {d5f408fa-00ea-4c2d-b9d1-cf8b91945307}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1203} "level"=5 "msg"="deleting model" "table"="Bridge" "uuid"="d5f408fa-00ea-4c2d-b9d1-cf8b91945307" "model"={"UUID":"d5f408fa-00ea-4c2d-b9d1-cf8b91945307","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-8471.sock: use of closed network connection" +--- PASS: TestClientServerInsertAndDelete (0.53s) === RUN TestClientServerInsertDuplicate -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5338.sock" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5338.sock" "sid"="" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="1396c164-4aff-40b0-866a-60b34a74ca3d" "model"={"UUID":"1396c164-4aff-40b0-866a-60b34a74ca3d","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="1396c164-4aff-40b0-866a-60b34a74ca3d" "model"={"UUID":"1396c164-4aff-40b0-866a-60b34a74ca3d","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="07265dba-d541-4fa7-90a1-ed2422b9335d" "model"={"UUID":"07265dba-d541-4fa7-90a1-ed2422b9335d","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 server: "caller"={"file":"server.go","line":211} "msg"="Skipping transaction DB commit due to error" "error"="failed to process operation" "operations"=[{"op":"insert","table":"Bridge","row":{"external_ids":{"GoMap":{"\"go\"":"awesome","\"docker\"":"made-for-each-other"}},"name":"foo"},"uuid":"07265dba-d541-4fa7-90a1-ed2422b9335d"}] "results"=[{,"uuid":{"uuid":"07265dba-d541-4fa7-90a1-ed2422b9335d"}},{,"error":"constraint violation","details":"operation would cause rows in the \"Bridge\" table to have identical values (foo) for index on column \"name\". First row, with UUID 07265dba-d541-4fa7-90a1-ed2422b9335d, was inserted by this transaction. Second row, with UUID [1396c164-4aff-40b0-866a-60b34a74ca3d], existed in the database before this operation and was not modified","uuid":{"uuid":""}}] "operation error"="constraint violation" -2024/05/20 02:46:04 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-5338.sock: use of closed network connection" ---- PASS: TestClientServerInsertDuplicate (0.02s) +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-6328.sock" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-6328.sock" "sid"="" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="709a10ce-1950-491c-b04c-28ed31b689aa" "model"={"UUID":"709a10ce-1950-491c-b04c-28ed31b689aa","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="709a10ce-1950-491c-b04c-28ed31b689aa" "model"={"UUID":"709a10ce-1950-491c-b04c-28ed31b689aa","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:foo] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="dbf0c452-58ef-4d0b-85d2-f7e3980d7d1e" "model"={"UUID":"dbf0c452-58ef-4d0b-85d2-f7e3980d7d1e","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 server: "caller"={"file":"server.go","line":211} "msg"="Skipping transaction DB commit due to error" "error"="failed to process operation" "operations"=[{"op":"insert","table":"Bridge","row":{"external_ids":{"GoMap":{"\"docker\"":"made-for-each-other","\"go\"":"awesome"}},"name":"foo"},"uuid":"dbf0c452-58ef-4d0b-85d2-f7e3980d7d1e"}] "results"=[{,"uuid":{"uuid":"dbf0c452-58ef-4d0b-85d2-f7e3980d7d1e"}},{,"error":"constraint violation","details":"operation would cause rows in the \"Bridge\" table to have identical values (foo) for index on column \"name\". First row, with UUID dbf0c452-58ef-4d0b-85d2-f7e3980d7d1e, was inserted by this transaction. Second row, with UUID [709a10ce-1950-491c-b04c-28ed31b689aa], existed in the database before this operation and was not modified","uuid":{"uuid":""}}] "operation error"="constraint violation" +2024/05/20 02:50:15 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-6328.sock: use of closed network connection" +--- PASS: TestClientServerInsertDuplicate (0.03s) === RUN TestClientServerInsertAndUpdate -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1849.sock" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-1849.sock" "sid"="" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:br-update] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "model"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "model"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "model"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[baz:foobar docker:made-for-each-other go:awesome]} other_config:{GoMap:map[foo:bar]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {85d78764-1578-4290-a97e-f747a10426e2}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"go":"awesome","docker":"made-for-each-other","baz":"foobar"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"docker":"made-for-each-other","go":"awesome","baz":"foobar"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"go":"awesome","docker":"made-for-each-other","baz":"foobar"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:04 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[foo:bar]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {85d78764-1578-4290-a97e-f747a10426e2}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:04 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" -2024/05/20 02:46:04 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} -2024/05/20 02:46:04 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="85d78764-1578-4290-a97e-f747a10426e2" "old"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"85d78764-1578-4290-a97e-f747a10426e2","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-1849.sock: use of closed network connection" ---- PASS: TestClientServerInsertAndUpdate (1.08s) +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8970.sock" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-8970.sock" "sid"="" +2024/05/20 02:50:15 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[external_ids:{GoMap:map[docker:made-for-each-other go:awesome]} name:br-update] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:15 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "model"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" +2024/05/20 02:50:15 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "model"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:15 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "model"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[baz:foobar docker:made-for-each-other go:awesome]} other_config:{GoMap:map[foo:bar]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {fa10a9c5-e6ef-4033-9c9e-82e7d44f6165}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"go":"awesome","docker":"made-for-each-other","baz":"foobar"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"go":"awesome","baz":"foobar","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","go":"awesome","docker":"made-for-each-other"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[foo:bar]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {fa10a9c5-e6ef-4033-9c9e-82e7d44f6165}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="fa10a9c5-e6ef-4033-9c9e-82e7d44f6165" "old"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"baz":"foobar","docker":"made-for-each-other","go":"awesome"},"Ports":[],"Status":{}} "new"={"UUID":"fa10a9c5-e6ef-4033-9c9e-82e7d44f6165","Name":"br-update","DatapathType":"","DatapathID":null,"OtherConfig":{"foo":"bar"},"ExternalIds":{"foo":"bar"},"Ports":[],"Status":{}} +2024/05/20 02:50:16 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-8970.sock: use of closed network connection" +--- PASS: TestClientServerInsertAndUpdate (1.09s) === RUN TestUnsetOptional -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9658.sock" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9658.sock" "sid"="" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[optional]} name:br-with-optional] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "model"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "model"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "model"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[datapath_id:{GoSet:[]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {4b2b881c-be9e-491b-818a-bcf3f7e9e67f}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "old"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "old"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="4b2b881c-be9e-491b-818a-bcf3f7e9e67f" "old"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"4b2b881c-be9e-491b-818a-bcf3f7e9e67f","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-9658.sock: use of closed network connection" ---- PASS: TestUnsetOptional (0.02s) +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-851.sock" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-851.sock" "sid"="" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[optional]} name:br-with-optional] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "model"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "model"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "model"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[datapath_id:{GoSet:[]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {92727fac-7636-4adf-b340-f1e26b519c0c}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "old"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "old"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="92727fac-7636-4adf-b340-f1e26b519c0c" "old"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":"optional","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"92727fac-7636-4adf-b340-f1e26b519c0c","Name":"br-with-optional","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-851.sock: use of closed network connection" +--- PASS: TestUnsetOptional (0.04s) === RUN TestUpdateOptional -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5317.sock" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-5317.sock" "sid"="" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[old]} name:br-with-optional] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "model"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "model"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "model"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[datapath_id:{GoSet:[new]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {9e7c6f51-48d5-4e87-8792-92a8ef30c46b}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "old"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "old"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9e7c6f51-48d5-4e87-8792-92a8ef30c46b" "old"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9e7c6f51-48d5-4e87-8792-92a8ef30c46b","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-5317.sock: use of closed network connection" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-949.sock" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-949.sock" "sid"="" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[old]} name:br-with-optional] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "model"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "model"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "model"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[datapath_id:{GoSet:[new]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {9be72802-ed4a-4698-8c0a-de6762a3f4a1}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "old"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "old"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9be72802-ed4a-4698-8c0a-de6762a3f4a1" "old"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"old","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} "new"={"UUID":"9be72802-ed4a-4698-8c0a-de6762a3f4a1","Name":"br-with-optional","DatapathType":"","DatapathID":"new","OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:16 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-949.sock: use of closed network connection" --- PASS: TestUpdateOptional (0.02s) === RUN TestMultipleOpsSameRow -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-4372.sock" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-4372.sock" "sid"="" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[datapathID]} external_ids:{GoMap:map[key1:value1]} name:bridge_multiple_ops_same_row ports:{GoSet:[{GoUUID:port10} {GoUUID:port1}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:bridge_multiple_ops_same_row}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "model"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "model"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "model"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} -2024/05/20 02:46:05 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" -2024/05/20 02:46:05 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[key1:value1 key10:value10]} ports:{GoSet:[{GoUUID:port10}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {9610fb77-410b-43be-ad5c-d4c49a211487}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:mutate Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[{Column:external_ids Mutator:insert Value:{GoMap:map[keyA:valueA]}} {Column:ports Mutator:insert Value:{GoSet:[{GoUUID:port1}]}}] Timeout: Where:[where column _uuid == {9610fb77-410b-43be-ad5c-d4c49a211487}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:mutate Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[{Column:external_ids Mutator:delete Value:{GoMap:map[key10:value10]}} {Column:ports Mutator:delete Value:{GoSet:[{GoUUID:port10}]}}] Timeout: Where:[where column _uuid == {9610fb77-410b-43be-ad5c-d4c49a211487}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:update Table:Bridge Row:map[datapath_id:{GoSet:[datapathID_updated]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {9610fb77-410b-43be-ad5c-d4c49a211487}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:update Table:Bridge Row:map[datapath_id:{GoSet:[]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {9610fb77-410b-43be-ad5c-d4c49a211487}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10"},"Ports":["port10"],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10"},"Ports":["port10"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10","keyA":"valueA"},"Ports":["port10","port1"],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10","keyA":"valueA"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID_updated","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID_updated","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" -2024/05/20 02:46:05 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"keyA":"valueA","key1":"value1"},"Ports":["port1"],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="9610fb77-410b-43be-ad5c-d4c49a211487" "old"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"9610fb77-410b-43be-ad5c-d4c49a211487","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} -2024/05/20 02:46:05 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-4372.sock: use of closed network connection" ---- PASS: TestMultipleOpsSameRow (0.02s) +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":332} "level"=3 "msg"="trying to connect" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9890.sock" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":263} "level"=3 "msg"="successfully connected" "database"="Open_vSwitch" "endpoint"="unix:/tmp/ovsdb-9890.sock" "sid"="" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:insert Table:Bridge Row:map[datapath_id:{GoSet:[datapathID]} external_ids:{GoMap:map[key1:value1]} name:bridge_multiple_ops_same_row ports:{GoSet:[{GoUUID:port10} {GoUUID:port1}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[] Until: Durable: Comment: Lock: UUID: UUIDName:bridge_multiple_ops_same_row}]" +2024/05/20 02:50:16 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "model"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" +2024/05/20 02:50:16 libovsdb/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "model"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} +2024/05/20 02:50:16 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "model"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:16 libovsdb: "caller"={"file":"api.go","line":421} "level"=2 "msg"="removing immutable field" "database"="Open_vSwitch" "name"="name" +2024/05/20 02:50:17 libovsdb: "caller"={"file":"client.go","line":837} "level"=4 "msg"="transacting operations" "database"="Open_vSwitch" "database"="Open_vSwitch" "operations"="[{Op:update Table:Bridge Row:map[external_ids:{GoMap:map[key1:value1 key10:value10]} ports:{GoSet:[{GoUUID:port10}]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {3cb590b3-a6da-4a2f-ae38-9061ee9de217}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:mutate Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[{Column:external_ids Mutator:insert Value:{GoMap:map[keyA:valueA]}} {Column:ports Mutator:insert Value:{GoSet:[{GoUUID:port1}]}}] Timeout: Where:[where column _uuid == {3cb590b3-a6da-4a2f-ae38-9061ee9de217}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:mutate Table:Bridge Row:map[] Rows:[] Columns:[] Mutations:[{Column:external_ids Mutator:delete Value:{GoMap:map[key10:value10]}} {Column:ports Mutator:delete Value:{GoSet:[{GoUUID:port10}]}}] Timeout: Where:[where column _uuid == {3cb590b3-a6da-4a2f-ae38-9061ee9de217}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:update Table:Bridge Row:map[datapath_id:{GoSet:[datapathID_updated]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {3cb590b3-a6da-4a2f-ae38-9061ee9de217}] Until: Durable: Comment: Lock: UUID: UUIDName:} {Op:update Table:Bridge Row:map[datapath_id:{GoSet:[]}] Rows:[] Columns:[] Mutations:[] Timeout: Where:[where column _uuid == {3cb590b3-a6da-4a2f-ae38-9061ee9de217}] Until: Durable: Comment: Lock: UUID: UUIDName:}]" +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key10":"value10","key1":"value1"},"Ports":["port10"],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10"},"Ports":["port10"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10","keyA":"valueA"},"Ports":["port10","port1"],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1","key10":"value10","keyA":"valueA"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"keyA":"valueA","key1":"value1"},"Ports":["port1"],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"keyA":"valueA","key1":"value1"},"Ports":["port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID_updated","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID_updated","OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} +2024/05/20 02:50:17 libovsdb/cache: "caller"={"file":"cache.go","line":996} "level"=5 "msg"="processing update" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" +2024/05/20 02:50:17 libovsdb/cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "database"="Open_vSwitch" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} +2024/05/20 02:50:17 cache: "caller"={"file":"cache.go","line":1196} "level"=5 "msg"="updating model" "table"="Bridge" "uuid"="3cb590b3-a6da-4a2f-ae38-9061ee9de217" "old"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":"datapathID","OtherConfig":{},"ExternalIds":{"key1":"value1"},"Ports":["port10","port1"],"Status":{}} "new"={"UUID":"3cb590b3-a6da-4a2f-ae38-9061ee9de217","Name":"bridge_multiple_ops_same_row","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{"key1":"value1","keyA":"valueA"},"Ports":["port1"],"Status":{}} +2024/05/20 02:50:17 server: "caller"={"file":"server.go","line":139} "msg"="failed to close listener" "error"="close unix /tmp/ovsdb-9890.sock: use of closed network connection" +--- PASS: TestMultipleOpsSameRow (0.03s) === RUN TestOvsdbServerMonitor -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="2a6f4e0c-2e1c-47d7-97bf-9dc5ce2c984f" "model"={"UUID":"2a6f4e0c-2e1c-47d7-97bf-9dc5ce2c984f","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="d8fe1390-ba13-47ab-a4fb-fbfade4adda5" "model"={"UUID":"d8fe1390-ba13-47ab-a4fb-fbfade4adda5","Name":"bar","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="2aefe508-4dd9-472e-b33c-e76171db08a1" "model"={"UUID":"2aefe508-4dd9-472e-b33c-e76171db08a1","Name":"baz","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="44a44b90-bfa9-4af3-b907-03fc95b4d953" "model"={"UUID":"44a44b90-bfa9-4af3-b907-03fc95b4d953","Name":"quux","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="2a6f4e0c-2e1c-47d7-97bf-9dc5ce2c984f" "model"={"UUID":"2a6f4e0c-2e1c-47d7-97bf-9dc5ce2c984f","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="d8fe1390-ba13-47ab-a4fb-fbfade4adda5" "model"={"UUID":"d8fe1390-ba13-47ab-a4fb-fbfade4adda5","Name":"bar","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="2aefe508-4dd9-472e-b33c-e76171db08a1" "model"={"UUID":"2aefe508-4dd9-472e-b33c-e76171db08a1","Name":"baz","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} -2024/05/20 02:46:05 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="44a44b90-bfa9-4af3-b907-03fc95b4d953" "model"={"UUID":"44a44b90-bfa9-4af3-b907-03fc95b4d953","Name":"quux","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="ffabba78-b8d7-45c7-b29a-7a96c71ef1d7" "model"={"UUID":"ffabba78-b8d7-45c7-b29a-7a96c71ef1d7","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="7d17885d-60c9-4d80-80d5-bb529fb2bc67" "model"={"UUID":"7d17885d-60c9-4d80-80d5-bb529fb2bc67","Name":"bar","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="12cb394e-ce82-4aed-87c8-0123152869dd" "model"={"UUID":"12cb394e-ce82-4aed-87c8-0123152869dd","Name":"baz","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 server/transaction/cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="76ff87c0-8893-41ea-a99d-4e65f96d87bf" "model"={"UUID":"76ff87c0-8893-41ea-a99d-4e65f96d87bf","Name":"quux","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="ffabba78-b8d7-45c7-b29a-7a96c71ef1d7" "model"={"UUID":"ffabba78-b8d7-45c7-b29a-7a96c71ef1d7","Name":"foo","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="7d17885d-60c9-4d80-80d5-bb529fb2bc67" "model"={"UUID":"7d17885d-60c9-4d80-80d5-bb529fb2bc67","Name":"bar","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="12cb394e-ce82-4aed-87c8-0123152869dd" "model"={"UUID":"12cb394e-ce82-4aed-87c8-0123152869dd","Name":"baz","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} +2024/05/20 02:50:17 cache: "caller"={"file":"cache.go","line":1189} "level"=5 "msg"="inserting model" "table"="Bridge" "uuid"="76ff87c0-8893-41ea-a99d-4e65f96d87bf" "model"={"UUID":"76ff87c0-8893-41ea-a99d-4e65f96d87bf","Name":"quux","DatapathType":"","DatapathID":null,"OtherConfig":{},"ExternalIds":{},"Ports":[],"Status":{}} --- PASS: TestOvsdbServerMonitor (0.00s) PASS -ok github.com/ovn-org/libovsdb/server 2.295s +ok github.com/ovn-org/libovsdb/server 2.427s === RUN TestDifference === RUN TestDifference/value,_different === RUN TestDifference/value,_equal @@ -2715,7 +2751,7 @@ === RUN Test_merge/update_set_field_to_original_value_after_update_results_in_no_op === RUN Test_merge/update_map_field_to_original_value_after_update_results_in_no_op === RUN Test_merge/update_multiple_fields_to_original_value_after_update_results_in_no_op ---- PASS: Test_merge (0.02s) +--- PASS: Test_merge (0.05s) --- PASS: Test_merge/no_op (0.00s) --- PASS: Test_merge/insert (0.00s) --- PASS: Test_merge/update (0.00s) @@ -2726,7 +2762,7 @@ --- PASS: Test_merge/insert_after_insert_fails (0.00s) --- PASS: Test_merge/insert_after_update_fails (0.00s) --- PASS: Test_merge/insert_after_delete_fails (0.00s) - --- PASS: Test_merge/update_after_insert (0.00s) + --- PASS: Test_merge/update_after_insert (0.01s) --- PASS: Test_merge/update_after_update (0.00s) --- PASS: Test_merge/update_after_delete_fails (0.00s) --- PASS: Test_merge/delete_after_insert (0.00s) @@ -2915,7 +2951,7 @@ --- PASS: TestModelUpdates_AddRowUpdate/update_no_op (0.00s) --- PASS: TestModelUpdates_AddRowUpdate/delete (0.00s) PASS -ok github.com/ovn-org/libovsdb/updates 0.050s +ok github.com/ovn-org/libovsdb/updates 0.089s create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=_build -O--buildsystem=golang dh_prep -O--builddirectory=_build -O--buildsystem=golang @@ -2942,12 +2978,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/29128/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/29128/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/14172 and its subdirectories -I: Current time: Sun May 19 14:46:20 -12 2024 -I: pbuilder-time-stamp: 1716173180 +I: removing directory /srv/workspace/pbuilder/29128 and its subdirectories +I: Current time: Mon May 20 16:50:37 +14 2024 +I: pbuilder-time-stamp: 1716173437