{"level":"warn","ts":"2026-06-02T21:22:58.526931Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:22:58.52702Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-02T21:22:58.527108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:22:58.527123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-02T21:22:58.527146Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-02T21:22:58.527842Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-06-02T21:22:58.527932Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-02T21:22:58.532693Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.502706ms"} {"level":"info","ts":"2026-06-02T21:22:58.539156Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-06-02T21:22:58.539367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T21:22:58.539396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-06-02T21:22:58.539517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T21:22:58.539546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-06-02T21:22:58.539646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-06-02T21:22:58.545137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T21:22:58.546669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T21:22:58.548161Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-02T21:22:58.550613Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T21:22:58.550944Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T21:22:58.551058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T21:22:58.551115Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T21:22:58.550826Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T21:22:58.554523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-06-02T21:22:58.554861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-02T21:22:58.559367Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-02T21:22:58.559538Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-02T21:22:58.559655Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-02T21:22:58.560318Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T21:22:58.560364Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T21:22:59.340509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T21:22:59.340574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T21:22:59.340618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-06-02T21:22:59.340656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-06-02T21:22:59.340673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-02T21:22:59.340694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-06-02T21:22:59.340723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-02T21:22:59.341954Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T21:22:59.34214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:22:59.342081Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:22:59.342297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T21:22:59.342372Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T21:22:59.342194Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:22:59.343345Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:22:59.343452Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:22:59.343502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:22:59.346052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T21:22:59.346074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-06-02T21:23:49.074335Z","caller":"traceutil/trace.go:171","msg":"trace[1283558347] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"230.555828ms","start":"2026-06-02T21:23:48.843761Z","end":"2026-06-02T21:23:49.074317Z","steps":["trace[1283558347] 'process raft request' (duration: 230.132259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:49.899591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832234297618528,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T21:23:50.235639Z","caller":"traceutil/trace.go:171","msg":"trace[586356697] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"868.954798ms","start":"2026-06-02T21:23:49.366656Z","end":"2026-06-02T21:23:50.23561Z","steps":["trace[586356697] 'process raft request' (duration: 868.780364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.236599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.366639Z","time spent":"869.08412ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:23:50.253864Z","caller":"traceutil/trace.go:171","msg":"trace[928595307] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"854.698902ms","start":"2026-06-02T21:23:49.399053Z","end":"2026-06-02T21:23:50.253751Z","steps":["trace[928595307] 'read index received' (duration: 836.228958ms)","trace[928595307] 'applied index is now lower than readState.Index' (duration: 18.467524ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:23:50.256322Z","caller":"traceutil/trace.go:171","msg":"trace[1721109082] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"701.555744ms","start":"2026-06-02T21:23:49.554674Z","end":"2026-06-02T21:23:50.25623Z","steps":["trace[1721109082] 'process raft request' (duration: 697.302589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.256322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.254834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:23:50.256502Z","caller":"traceutil/trace.go:171","msg":"trace[1905181421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"857.477138ms","start":"2026-06-02T21:23:49.399Z","end":"2026-06-02T21:23:50.256477Z","steps":["trace[1905181421] 'agreement among raft nodes before linearized reading' (duration: 857.205653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.256521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.487953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-02T21:23:50.256548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.460577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-02T21:23:50.256554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.542814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-02T21:23:50.256636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.368064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T21:23:50.256642Z","caller":"traceutil/trace.go:171","msg":"trace[1751565853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"657.567239ms","start":"2026-06-02T21:23:49.599033Z","end":"2026-06-02T21:23:50.2566Z","steps":["trace[1751565853] 'agreement among raft nodes before linearized reading' (duration: 657.353255ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:23:50.256617Z","caller":"traceutil/trace.go:171","msg":"trace[2135995939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"654.561534ms","start":"2026-06-02T21:23:49.602005Z","end":"2026-06-02T21:23:50.256567Z","steps":["trace[2135995939] 'agreement among raft nodes before linearized reading' (duration: 654.394841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.257478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.599011Z","time spent":"658.449848ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T21:23:50.257522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.601994Z","time spent":"655.505334ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T21:23:50.257508Z","caller":"traceutil/trace.go:171","msg":"trace[1728148428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"668.482636ms","start":"2026-06-02T21:23:49.588191Z","end":"2026-06-02T21:23:50.256673Z","steps":["trace[1728148428] 'agreement among raft nodes before linearized reading' (duration: 668.192641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.256579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.554643Z","time spent":"701.82271ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:23:50.256577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.398979Z","time spent":"857.573271ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T21:23:50.256673Z","caller":"traceutil/trace.go:171","msg":"trace[1404566469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"777.668986ms","start":"2026-06-02T21:23:49.478991Z","end":"2026-06-02T21:23:50.25666Z","steps":["trace[1404566469] 'agreement among raft nodes before linearized reading' (duration: 777.327679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:23:50.257831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.478927Z","time spent":"778.892711ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T21:23:50.258173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:23:49.58817Z","time spent":"669.450016ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T21:23:51.750868Z","caller":"traceutil/trace.go:171","msg":"trace[1626242177] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"178.467137ms","start":"2026-06-02T21:23:51.572361Z","end":"2026-06-02T21:23:51.750828Z","steps":["trace[1626242177] 'process raft request' (duration: 177.985377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:24:04.499163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.127494ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:24:04.499499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.420674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-02T21:24:04.499308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.558577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T21:24:04.499612Z","caller":"traceutil/trace.go:171","msg":"trace[1803264797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"129.579484ms","start":"2026-06-02T21:24:04.370009Z","end":"2026-06-02T21:24:04.499588Z","steps":["trace[1803264797] 'range keys from in-memory index tree' (duration: 129.216173ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.499625Z","caller":"traceutil/trace.go:171","msg":"trace[351991397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"131.882049ms","start":"2026-06-02T21:24:04.367714Z","end":"2026-06-02T21:24:04.499596Z","steps":["trace[351991397] 'range keys from in-memory index tree' (duration: 131.394927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:24:04.499368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.818025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T21:24:04.499807Z","caller":"traceutil/trace.go:171","msg":"trace[1672365722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"155.287726ms","start":"2026-06-02T21:24:04.344498Z","end":"2026-06-02T21:24:04.499786Z","steps":["trace[1672365722] 'range keys from in-memory index tree' (duration: 154.686543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:24:04.499384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.954747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T21:24:04.499864Z","caller":"traceutil/trace.go:171","msg":"trace[579010272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"164.495058ms","start":"2026-06-02T21:24:04.335355Z","end":"2026-06-02T21:24:04.49985Z","steps":["trace[579010272] 'range keys from in-memory index tree' (duration: 163.775255ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.499779Z","caller":"traceutil/trace.go:171","msg":"trace[1906396185] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:800; }","duration":"138.776316ms","start":"2026-06-02T21:24:04.360989Z","end":"2026-06-02T21:24:04.499765Z","steps":["trace[1906396185] 'range keys from in-memory index tree' (duration: 138.107994ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.707532Z","caller":"traceutil/trace.go:171","msg":"trace[1650188144] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"203.904744ms","start":"2026-06-02T21:24:04.503593Z","end":"2026-06-02T21:24:04.707498Z","steps":["trace[1650188144] 'process raft request' (duration: 203.744273ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.716753Z","caller":"traceutil/trace.go:171","msg":"trace[433930277] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"212.394234ms","start":"2026-06-02T21:24:04.504337Z","end":"2026-06-02T21:24:04.716732Z","steps":["trace[433930277] 'process raft request' (duration: 212.189413ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.716871Z","caller":"traceutil/trace.go:171","msg":"trace[1275454034] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"211.02777ms","start":"2026-06-02T21:24:04.505819Z","end":"2026-06-02T21:24:04.716847Z","steps":["trace[1275454034] 'process raft request' (duration: 210.86808ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.716836Z","caller":"traceutil/trace.go:171","msg":"trace[1465767630] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"212.325314ms","start":"2026-06-02T21:24:04.504461Z","end":"2026-06-02T21:24:04.716786Z","steps":["trace[1465767630] 'process raft request' (duration: 212.159293ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:04.716859Z","caller":"traceutil/trace.go:171","msg":"trace[978147314] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"212.348984ms","start":"2026-06-02T21:24:04.504494Z","end":"2026-06-02T21:24:04.716843Z","steps":["trace[978147314] 'process raft request' (duration: 212.156824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:24:38.259819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.179608ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T21:24:38.259904Z","caller":"traceutil/trace.go:171","msg":"trace[1667474992] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"257.603065ms","start":"2026-06-02T21:24:38.002288Z","end":"2026-06-02T21:24:38.259891Z","steps":["trace[1667474992] 'read index received' (duration: 57.718µs)","trace[1667474992] 'applied index is now lower than readState.Index' (duration: 257.542787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:24:38.260069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.78936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-06-02T21:24:38.260279Z","caller":"traceutil/trace.go:171","msg":"trace[1680500910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1224; }","duration":"258.051813ms","start":"2026-06-02T21:24:38.00222Z","end":"2026-06-02T21:24:38.260272Z","steps":["trace[1680500910] 'agreement among raft nodes before linearized reading' (duration: 257.796369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:24:38.260409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.150978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-02T21:24:38.260475Z","caller":"traceutil/trace.go:171","msg":"trace[45010884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"183.261475ms","start":"2026-06-02T21:24:38.077201Z","end":"2026-06-02T21:24:38.260462Z","steps":["trace[45010884] 'agreement among raft nodes before linearized reading' (duration: 183.07881ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:24:54.802176Z","caller":"traceutil/trace.go:171","msg":"trace[1299441197] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"121.735263ms","start":"2026-06-02T21:24:54.680419Z","end":"2026-06-02T21:24:54.802154Z","steps":["trace[1299441197] 'process raft request' (duration: 121.602688ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:26.212145Z","caller":"traceutil/trace.go:171","msg":"trace[1322047357] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"378.332253ms","start":"2026-06-02T21:25:25.833787Z","end":"2026-06-02T21:25:26.21212Z","steps":["trace[1322047357] 'read index received' (duration: 378.241198ms)","trace[1322047357] 'applied index is now lower than readState.Index' (duration: 90.065µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:25:26.212291Z","caller":"traceutil/trace.go:171","msg":"trace[1095815233] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"414.034088ms","start":"2026-06-02T21:25:25.798152Z","end":"2026-06-02T21:25:26.212186Z","steps":["trace[1095815233] 'process raft request' (duration: 413.830428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:25:26.212532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.708315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3a50962-5a6b-44a5-be7f-2d505b21c8d0\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-02T21:25:26.212558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:25:25.798132Z","time spent":"414.291205ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:25:26.212587Z","caller":"traceutil/trace.go:171","msg":"trace[1151888950] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c3a50962-5a6b-44a5-be7f-2d505b21c8d0; range_end:; response_count:1; response_revision:1892; }","duration":"378.847707ms","start":"2026-06-02T21:25:25.833724Z","end":"2026-06-02T21:25:26.212572Z","steps":["trace[1151888950] 'agreement among raft nodes before linearized reading' (duration: 378.515394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:25:26.212667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:25:25.833696Z","time spent":"378.958282ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3a50962-5a6b-44a5-be7f-2d505b21c8d0\" "} {"level":"info","ts":"2026-06-02T21:25:26.225506Z","caller":"traceutil/trace.go:171","msg":"trace[2141278386] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"262.54527ms","start":"2026-06-02T21:25:25.96294Z","end":"2026-06-02T21:25:26.225485Z","steps":["trace[2141278386] 'process raft request' (duration: 262.406727ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:26.327561Z","caller":"traceutil/trace.go:171","msg":"trace[173359015] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"107.811112ms","start":"2026-06-02T21:25:26.219723Z","end":"2026-06-02T21:25:26.327534Z","steps":["trace[173359015] 'process raft request' (duration: 102.076079ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:26.623881Z","caller":"traceutil/trace.go:171","msg":"trace[1924607975] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1974; }","duration":"224.840573ms","start":"2026-06-02T21:25:26.39902Z","end":"2026-06-02T21:25:26.62386Z","steps":["trace[1924607975] 'read index received' (duration: 4.869535ms)","trace[1924607975] 'applied index is now lower than readState.Index' (duration: 219.969948ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:25:26.624161Z","caller":"traceutil/trace.go:171","msg":"trace[2008217551] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"226.900831ms","start":"2026-06-02T21:25:26.397238Z","end":"2026-06-02T21:25:26.624139Z","steps":["trace[2008217551] 'process raft request' (duration: 226.564048ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:26.624338Z","caller":"traceutil/trace.go:171","msg":"trace[1890374070] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"227.211105ms","start":"2026-06-02T21:25:26.39711Z","end":"2026-06-02T21:25:26.624321Z","steps":["trace[1890374070] 'process raft request' (duration: 192.231047ms)","trace[1890374070] 'compare' (duration: 34.287063ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:25:26.624314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.253393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:25:26.624409Z","caller":"traceutil/trace.go:171","msg":"trace[93514115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1899; }","duration":"225.388886ms","start":"2026-06-02T21:25:26.398997Z","end":"2026-06-02T21:25:26.624386Z","steps":["trace[93514115] 'agreement among raft nodes before linearized reading' (duration: 225.207625ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:26.624443Z","caller":"traceutil/trace.go:171","msg":"trace[147000539] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"226.855613ms","start":"2026-06-02T21:25:26.397172Z","end":"2026-06-02T21:25:26.624028Z","steps":["trace[147000539] 'process raft request' (duration: 226.579277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:25:26.662371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.568054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T21:25:26.662455Z","caller":"traceutil/trace.go:171","msg":"trace[1731387362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1900; }","duration":"141.678247ms","start":"2026-06-02T21:25:26.520755Z","end":"2026-06-02T21:25:26.662433Z","steps":["trace[1731387362] 'agreement among raft nodes before linearized reading' (duration: 141.473558ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:39.082488Z","caller":"traceutil/trace.go:171","msg":"trace[68305388] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"116.271339ms","start":"2026-06-02T21:25:38.966191Z","end":"2026-06-02T21:25:39.082463Z","steps":["trace[68305388] 'process raft request' (duration: 116.041392ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:25:42.878223Z","caller":"traceutil/trace.go:171","msg":"trace[1236830896] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"115.038232ms","start":"2026-06-02T21:25:42.763159Z","end":"2026-06-02T21:25:42.878197Z","steps":["trace[1236830896] 'process raft request' (duration: 114.808584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:15.099337Z","caller":"traceutil/trace.go:171","msg":"trace[399002191] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"100.595649ms","start":"2026-06-02T21:26:14.998706Z","end":"2026-06-02T21:26:15.099302Z","steps":["trace[399002191] 'read index received' (duration: 100.307137ms)","trace[399002191] 'applied index is now lower than readState.Index' (duration: 287.222µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:26:15.099529Z","caller":"traceutil/trace.go:171","msg":"trace[2040117858] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"103.231838ms","start":"2026-06-02T21:26:14.996281Z","end":"2026-06-02T21:26:15.099513Z","steps":["trace[2040117858] 'process raft request' (duration: 102.808014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.099886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.120328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:26:15.100038Z","caller":"traceutil/trace.go:171","msg":"trace[256440687] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2209; }","duration":"101.317555ms","start":"2026-06-02T21:26:14.99867Z","end":"2026-06-02T21:26:15.099987Z","steps":["trace[256440687] 'agreement among raft nodes before linearized reading' (duration: 100.997505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.906015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.402085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:26:15.906291Z","caller":"traceutil/trace.go:171","msg":"trace[1168758712] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"357.885773ms","start":"2026-06-02T21:26:15.548388Z","end":"2026-06-02T21:26:15.906273Z","steps":["trace[1168758712] 'process raft request' (duration: 357.824277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.906355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.548359Z","time spent":"357.964358ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:26:15.906465Z","caller":"traceutil/trace.go:171","msg":"trace[1103467348] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"641.18946ms","start":"2026-06-02T21:26:15.265266Z","end":"2026-06-02T21:26:15.906455Z","steps":["trace[1103467348] 'process raft request' (duration: 207.278005ms)","trace[1103467348] 'compare' (duration: 433.242925ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:26:15.906512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.26524Z","time spent":"641.246556ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:26:15.906594Z","caller":"traceutil/trace.go:171","msg":"trace[1987685259] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"545.352473ms","start":"2026-06-02T21:26:15.361233Z","end":"2026-06-02T21:26:15.906585Z","steps":["trace[1987685259] 'read index received' (duration: 111.224912ms)","trace[1987685259] 'applied index is now lower than readState.Index' (duration: 434.126661ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:26:15.906734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.504924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T21:26:15.906761Z","caller":"traceutil/trace.go:171","msg":"trace[1187638404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2213; }","duration":"545.557651ms","start":"2026-06-02T21:26:15.361193Z","end":"2026-06-02T21:26:15.906751Z","steps":["trace[1187638404] 'agreement among raft nodes before linearized reading' (duration: 545.450178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.906792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.36118Z","time spent":"545.605668ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T21:26:15.906963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.203449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:26:15.907062Z","caller":"traceutil/trace.go:171","msg":"trace[214314127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2213; }","duration":"510.369759ms","start":"2026-06-02T21:26:15.396668Z","end":"2026-06-02T21:26:15.907038Z","steps":["trace[214314127] 'agreement among raft nodes before linearized reading' (duration: 510.214738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.90711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.396642Z","time spent":"510.458163ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T21:26:15.907163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.477353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T21:26:15.907208Z","caller":"traceutil/trace.go:171","msg":"trace[1527862081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2213; }","duration":"497.558228ms","start":"2026-06-02T21:26:15.40964Z","end":"2026-06-02T21:26:15.907198Z","steps":["trace[1527862081] 'agreement among raft nodes before linearized reading' (duration: 497.458384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.907238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.409623Z","time spent":"497.608005ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T21:26:15.907361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.886611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-02T21:26:15.907387Z","caller":"traceutil/trace.go:171","msg":"trace[1233225637] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2213; }","duration":"137.971217ms","start":"2026-06-02T21:26:15.76941Z","end":"2026-06-02T21:26:15.907381Z","steps":["trace[1233225637] 'agreement among raft nodes before linearized reading' (duration: 137.905561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.907417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.753766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T21:26:15.907468Z","caller":"traceutil/trace.go:171","msg":"trace[1669325281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2213; }","duration":"497.818571ms","start":"2026-06-02T21:26:15.409634Z","end":"2026-06-02T21:26:15.907453Z","steps":["trace[1669325281] 'agreement among raft nodes before linearized reading' (duration: 497.6696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.907502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.409622Z","time spent":"497.873019ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T21:26:15.907749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.813755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T21:26:15.907787Z","caller":"traceutil/trace.go:171","msg":"trace[1929671786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2213; }","duration":"506.890181ms","start":"2026-06-02T21:26:15.400886Z","end":"2026-06-02T21:26:15.907777Z","steps":["trace[1929671786] 'agreement among raft nodes before linearized reading' (duration: 506.796346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:15.907819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:15.400865Z","time spent":"506.946457ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T21:26:21.394375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.710506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-02T21:26:21.394455Z","caller":"traceutil/trace.go:171","msg":"trace[1745906878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2240; }","duration":"189.840887ms","start":"2026-06-02T21:26:21.204598Z","end":"2026-06-02T21:26:21.394439Z","steps":["trace[1745906878] 'range keys from in-memory index tree' (duration: 189.531757ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:21.45443Z","caller":"traceutil/trace.go:171","msg":"trace[875196739] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"198.89468ms","start":"2026-06-02T21:26:21.255484Z","end":"2026-06-02T21:26:21.454378Z","steps":["trace[875196739] 'process raft request' (duration: 198.637196ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:41.313703Z","caller":"traceutil/trace.go:171","msg":"trace[203659359] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"126.409509ms","start":"2026-06-02T21:26:41.187266Z","end":"2026-06-02T21:26:41.313675Z","steps":["trace[203659359] 'process raft request' (duration: 126.213831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.565579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832234297622151,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T21:26:42.59349Z","caller":"traceutil/trace.go:171","msg":"trace[927592703] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"566.737749ms","start":"2026-06-02T21:26:42.026729Z","end":"2026-06-02T21:26:42.593466Z","steps":["trace[927592703] 'process raft request' (duration: 566.613607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.593653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.026718Z","time spent":"566.843952ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:26:42.598536Z","caller":"traceutil/trace.go:171","msg":"trace[1292192782] linearizableReadLoop","detail":"{readStateIndex:2466; appliedIndex:2463; }","duration":"533.238711ms","start":"2026-06-02T21:26:42.065282Z","end":"2026-06-02T21:26:42.598521Z","steps":["trace[1292192782] 'read index received' (duration: 528.133887ms)","trace[1292192782] 'applied index is now lower than readState.Index' (duration: 5.103864ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:26:42.598829Z","caller":"traceutil/trace.go:171","msg":"trace[183652169] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"102.537673ms","start":"2026-06-02T21:26:42.496273Z","end":"2026-06-02T21:26:42.598811Z","steps":["trace[183652169] 'process raft request' (duration: 102.213514ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:42.598851Z","caller":"traceutil/trace.go:171","msg":"trace[1232414777] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"154.157892ms","start":"2026-06-02T21:26:42.444655Z","end":"2026-06-02T21:26:42.598813Z","steps":["trace[1232414777] 'process raft request' (duration: 153.752968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.599118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.233113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-02T21:26:42.599188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.414608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5460"} {"level":"info","ts":"2026-06-02T21:26:42.59922Z","caller":"traceutil/trace.go:171","msg":"trace[1115715343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2371; }","duration":"530.348025ms","start":"2026-06-02T21:26:42.068848Z","end":"2026-06-02T21:26:42.599196Z","steps":["trace[1115715343] 'agreement among raft nodes before linearized reading' (duration: 530.107831ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:42.599235Z","caller":"traceutil/trace.go:171","msg":"trace[1585573335] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2371; }","duration":"503.487173ms","start":"2026-06-02T21:26:42.095733Z","end":"2026-06-02T21:26:42.59922Z","steps":["trace[1585573335] 'agreement among raft nodes before linearized reading' (duration: 503.364751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.599181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.471207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T21:26:42.59928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.095719Z","time spent":"503.552148ms","remote":"127.0.0.1:34902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5483,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-02T21:26:42.599286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.068837Z","time spent":"530.43396ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T21:26:42.599342Z","caller":"traceutil/trace.go:171","msg":"trace[197104270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2371; }","duration":"530.629848ms","start":"2026-06-02T21:26:42.068668Z","end":"2026-06-02T21:26:42.599298Z","steps":["trace[197104270] 'agreement among raft nodes before linearized reading' (duration: 530.341636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.599367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.447263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T21:26:42.599423Z","caller":"traceutil/trace.go:171","msg":"trace[583660884] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:2371; }","duration":"154.532418ms","start":"2026-06-02T21:26:42.444876Z","end":"2026-06-02T21:26:42.599408Z","steps":["trace[583660884] 'agreement among raft nodes before linearized reading' (duration: 154.389187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.599436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.068657Z","time spent":"530.765539ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T21:26:42.599706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.620276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-02T21:26:42.598997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.995857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:26:42.59975Z","caller":"traceutil/trace.go:171","msg":"trace[606424433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2371; }","duration":"534.533527ms","start":"2026-06-02T21:26:42.065205Z","end":"2026-06-02T21:26:42.599739Z","steps":["trace[606424433] 'agreement among raft nodes before linearized reading' (duration: 533.526502ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:26:42.599851Z","caller":"traceutil/trace.go:171","msg":"trace[1663610462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2371; }","duration":"201.938896ms","start":"2026-06-02T21:26:42.397901Z","end":"2026-06-02T21:26:42.59984Z","steps":["trace[1663610462] 'agreement among raft nodes before linearized reading' (duration: 200.964868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.599865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.06518Z","time spent":"534.671929ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T21:26:42.599755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.650437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T21:26:42.603494Z","caller":"traceutil/trace.go:171","msg":"trace[1201650488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2371; }","duration":"536.442465ms","start":"2026-06-02T21:26:42.067038Z","end":"2026-06-02T21:26:42.603481Z","steps":["trace[1201650488] 'agreement among raft nodes before linearized reading' (duration: 531.733427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:26:42.603529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:26:42.06702Z","time spent":"536.496762ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T21:27:29.698188Z","caller":"traceutil/trace.go:171","msg":"trace[1364366129] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"178.809368ms","start":"2026-06-02T21:27:29.519346Z","end":"2026-06-02T21:27:29.698155Z","steps":["trace[1364366129] 'process raft request' (duration: 178.591667ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:27:29.906103Z","caller":"traceutil/trace.go:171","msg":"trace[2109788157] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"138.593843ms","start":"2026-06-02T21:27:29.767482Z","end":"2026-06-02T21:27:29.906076Z","steps":["trace[2109788157] 'process raft request' (duration: 138.389593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:27:29.906339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.503237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:27:29.906417Z","caller":"traceutil/trace.go:171","msg":"trace[1847944707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2724; }","duration":"123.673793ms","start":"2026-06-02T21:27:29.782726Z","end":"2026-06-02T21:27:29.9064Z","steps":["trace[1847944707] 'agreement among raft nodes before linearized reading' (duration: 123.446041ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:27:29.906097Z","caller":"traceutil/trace.go:171","msg":"trace[902988062] linearizableReadLoop","detail":"{readStateIndex:2831; appliedIndex:2830; }","duration":"123.284096ms","start":"2026-06-02T21:27:29.78276Z","end":"2026-06-02T21:27:29.906044Z","steps":["trace[902988062] 'read index received' (duration: 123.073436ms)","trace[902988062] 'applied index is now lower than readState.Index' (duration: 209.51µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:27:30.319956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.872614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b560c5b4294f63\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-02T21:27:30.320016Z","caller":"traceutil/trace.go:171","msg":"trace[1270795665] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b560c5b4294f63; range_end:; response_count:1; response_revision:2724; }","duration":"375.980434ms","start":"2026-06-02T21:27:29.944023Z","end":"2026-06-02T21:27:30.320003Z","steps":["trace[1270795665] 'range keys from in-memory index tree' (duration: 375.711378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:27:30.320044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:27:29.944005Z","time spent":"376.032278ms","remote":"127.0.0.1:48698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b560c5b4294f63\" "} {"level":"info","ts":"2026-06-02T21:31:00.720526Z","caller":"traceutil/trace.go:171","msg":"trace[536722017] transaction","detail":"{read_only:false; response_revision:4024; number_of_response:1; }","duration":"201.136618ms","start":"2026-06-02T21:31:00.519361Z","end":"2026-06-02T21:31:00.720498Z","steps":["trace[536722017] 'process raft request' (duration: 200.832394ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:13.562019Z","caller":"traceutil/trace.go:171","msg":"trace[468409905] linearizableReadLoop","detail":"{readStateIndex:4754; appliedIndex:4753; }","duration":"138.462465ms","start":"2026-06-02T21:31:13.423533Z","end":"2026-06-02T21:31:13.561996Z","steps":["trace[468409905] 'read index received' (duration: 96.840504ms)","trace[468409905] 'applied index is now lower than readState.Index' (duration: 41.620981ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:13.562092Z","caller":"traceutil/trace.go:171","msg":"trace[1945894641] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"141.79301ms","start":"2026-06-02T21:31:13.420258Z","end":"2026-06-02T21:31:13.562052Z","steps":["trace[1945894641] 'process raft request' (duration: 100.077372ms)","trace[1945894641] 'compare' (duration: 41.546042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:13.562281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.728409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:58304"} {"level":"info","ts":"2026-06-02T21:31:13.562332Z","caller":"traceutil/trace.go:171","msg":"trace[1476842835] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4572; }","duration":"138.801677ms","start":"2026-06-02T21:31:13.423518Z","end":"2026-06-02T21:31:13.56232Z","steps":["trace[1476842835] 'agreement among raft nodes before linearized reading' (duration: 138.590912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:13.574359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.876174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qn7sh\" ","response":"range_response_count:1 size:9352"} {"level":"info","ts":"2026-06-02T21:31:13.574425Z","caller":"traceutil/trace.go:171","msg":"trace[2026734326] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qn7sh; range_end:; response_count:1; response_revision:4574; }","duration":"118.953701ms","start":"2026-06-02T21:31:13.455457Z","end":"2026-06-02T21:31:13.574411Z","steps":["trace[2026734326] 'agreement among raft nodes before linearized reading' (duration: 118.784575ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:13.574649Z","caller":"traceutil/trace.go:171","msg":"trace[1421664947] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"141.503238ms","start":"2026-06-02T21:31:13.433123Z","end":"2026-06-02T21:31:13.574626Z","steps":["trace[1421664947] 'process raft request' (duration: 140.96313ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:13.574662Z","caller":"traceutil/trace.go:171","msg":"trace[25112576] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"117.133282ms","start":"2026-06-02T21:31:13.457486Z","end":"2026-06-02T21:31:13.574619Z","steps":["trace[25112576] 'process raft request' (duration: 116.715221ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:14.276414Z","caller":"traceutil/trace.go:171","msg":"trace[1624134488] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4607; }","duration":"100.018375ms","start":"2026-06-02T21:31:14.176366Z","end":"2026-06-02T21:31:14.276384Z","steps":["trace[1624134488] 'agreement among raft nodes before linearized reading' (duration: 99.859348ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:14.276429Z","caller":"traceutil/trace.go:171","msg":"trace[1552634418] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"126.987287ms","start":"2026-06-02T21:31:14.149401Z","end":"2026-06-02T21:31:14.276388Z","steps":["trace[1552634418] 'process raft request' (duration: 99.204023ms)","trace[1552634418] 'compare' (duration: 27.172078ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:15.64926Z","caller":"traceutil/trace.go:171","msg":"trace[556486774] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"156.817716ms","start":"2026-06-02T21:31:15.492409Z","end":"2026-06-02T21:31:15.649226Z","steps":["trace[556486774] 'process raft request' (duration: 156.462274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:20.980818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.080282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec3fd55a-8e4b-4da2-943f-5ea681751395\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-02T21:31:20.980906Z","caller":"traceutil/trace.go:171","msg":"trace[1283983447] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec3fd55a-8e4b-4da2-943f-5ea681751395; range_end:; response_count:1; response_revision:4716; }","duration":"130.250428ms","start":"2026-06-02T21:31:20.850634Z","end":"2026-06-02T21:31:20.980884Z","steps":["trace[1283983447] 'range keys from in-memory index tree' (duration: 129.898796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:20.980903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.525714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:31:20.980997Z","caller":"traceutil/trace.go:171","msg":"trace[1231065690] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4716; }","duration":"244.67455ms","start":"2026-06-02T21:31:20.736297Z","end":"2026-06-02T21:31:20.980971Z","steps":["trace[1231065690] 'count revisions from in-memory index tree' (duration: 244.422175ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:21.820883Z","caller":"traceutil/trace.go:171","msg":"trace[1255106763] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4905; }","duration":"292.102574ms","start":"2026-06-02T21:31:21.528762Z","end":"2026-06-02T21:31:21.820864Z","steps":["trace[1255106763] 'read index received' (duration: 292.030665ms)","trace[1255106763] 'applied index is now lower than readState.Index' (duration: 71.079µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:21.820927Z","caller":"traceutil/trace.go:171","msg":"trace[647299036] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"296.519458ms","start":"2026-06-02T21:31:21.524366Z","end":"2026-06-02T21:31:21.820894Z","steps":["trace[647299036] 'process raft request' (duration: 296.373962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:21.821203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.427256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec3fd55a-8e4b-4da2-943f-5ea681751395\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-02T21:31:21.821273Z","caller":"traceutil/trace.go:171","msg":"trace[378847161] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec3fd55a-8e4b-4da2-943f-5ea681751395; range_end:; response_count:1; response_revision:4720; }","duration":"292.521634ms","start":"2026-06-02T21:31:21.528736Z","end":"2026-06-02T21:31:21.821258Z","steps":["trace[378847161] 'agreement among raft nodes before linearized reading' (duration: 292.209842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:21.836488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.357755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:31:21.83654Z","caller":"traceutil/trace.go:171","msg":"trace[2073916764] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4721; }","duration":"186.461723ms","start":"2026-06-02T21:31:21.650066Z","end":"2026-06-02T21:31:21.836528Z","steps":["trace[2073916764] 'agreement among raft nodes before linearized reading' (duration: 186.367885ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:21.836549Z","caller":"traceutil/trace.go:171","msg":"trace[1317771266] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"121.822514ms","start":"2026-06-02T21:31:21.714702Z","end":"2026-06-02T21:31:21.836524Z","steps":["trace[1317771266] 'process raft request' (duration: 121.511651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:21.836642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.559986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-02T21:31:21.836724Z","caller":"traceutil/trace.go:171","msg":"trace[2015986768] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4721; }","duration":"208.699482ms","start":"2026-06-02T21:31:21.628006Z","end":"2026-06-02T21:31:21.836705Z","steps":["trace[2015986768] 'agreement among raft nodes before linearized reading' (duration: 208.464438ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:22.017748Z","caller":"traceutil/trace.go:171","msg":"trace[475837267] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"116.722865ms","start":"2026-06-02T21:31:21.901005Z","end":"2026-06-02T21:31:22.017728Z","steps":["trace[475837267] 'process raft request' (duration: 105.001466ms)","trace[475837267] 'compare' (duration: 11.608071ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:23.389678Z","caller":"traceutil/trace.go:171","msg":"trace[118414218] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"124.310512ms","start":"2026-06-02T21:31:23.265344Z","end":"2026-06-02T21:31:23.389655Z","steps":["trace[118414218] 'process raft request' (duration: 124.150606ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:28.729874Z","caller":"traceutil/trace.go:171","msg":"trace[59982181] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"116.609515ms","start":"2026-06-02T21:31:28.613241Z","end":"2026-06-02T21:31:28.72985Z","steps":["trace[59982181] 'process raft request' (duration: 116.451699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.965185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.209366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T21:31:32.965275Z","caller":"traceutil/trace.go:171","msg":"trace[895754790] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:4867; }","duration":"503.352002ms","start":"2026-06-02T21:31:32.461905Z","end":"2026-06-02T21:31:32.965257Z","steps":["trace[895754790] 'range keys from in-memory index tree' (duration: 502.899214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.965342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:32.461885Z","time spent":"503.44576ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-06-02T21:31:32.965455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.550173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T21:31:32.965557Z","caller":"traceutil/trace.go:171","msg":"trace[1271310421] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4867; }","duration":"502.691539ms","start":"2026-06-02T21:31:32.462838Z","end":"2026-06-02T21:31:32.96553Z","steps":["trace[1271310421] 'range keys from in-memory index tree' (duration: 502.254691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.965547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.385858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-06-02T21:31:32.965663Z","caller":"traceutil/trace.go:171","msg":"trace[905960168] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4867; }","duration":"337.700499ms","start":"2026-06-02T21:31:32.62793Z","end":"2026-06-02T21:31:32.96563Z","steps":["trace[905960168] 'range keys from in-memory index tree' (duration: 337.058196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.965699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.595074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-02T21:31:32.96579Z","caller":"traceutil/trace.go:171","msg":"trace[1499660089] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4867; }","duration":"243.720271ms","start":"2026-06-02T21:31:32.722046Z","end":"2026-06-02T21:31:32.965766Z","steps":["trace[1499660089] 'range keys from in-memory index tree' (duration: 243.102927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.965764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:32.627914Z","time spent":"337.823146ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-02T21:31:32.965869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.979438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T21:31:32.96598Z","caller":"traceutil/trace.go:171","msg":"trace[1319491761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4867; }","duration":"606.155654ms","start":"2026-06-02T21:31:32.359793Z","end":"2026-06-02T21:31:32.965949Z","steps":["trace[1319491761] 'range keys from in-memory index tree' (duration: 604.973814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:32.966025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:32.35977Z","time spent":"606.243011ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T21:31:32.966784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:32.462817Z","time spent":"502.796306ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T21:31:32.972221Z","caller":"traceutil/trace.go:171","msg":"trace[10612835] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"223.58087ms","start":"2026-06-02T21:31:32.748625Z","end":"2026-06-02T21:31:32.972206Z","steps":["trace[10612835] 'process raft request' (duration: 223.465322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:33.157817Z","caller":"traceutil/trace.go:171","msg":"trace[736131244] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"140.084608ms","start":"2026-06-02T21:31:33.017699Z","end":"2026-06-02T21:31:33.157783Z","steps":["trace[736131244] 'process raft request' (duration: 98.593774ms)","trace[736131244] 'compare' (duration: 41.318728ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:33.157957Z","caller":"traceutil/trace.go:171","msg":"trace[339528921] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"140.091538ms","start":"2026-06-02T21:31:33.017854Z","end":"2026-06-02T21:31:33.157946Z","steps":["trace[339528921] 'process raft request' (duration: 139.873603ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:33.158358Z","caller":"traceutil/trace.go:171","msg":"trace[805875855] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"140.443628ms","start":"2026-06-02T21:31:33.017898Z","end":"2026-06-02T21:31:33.158342Z","steps":["trace[805875855] 'process raft request' (duration: 140.00437ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:33.158395Z","caller":"traceutil/trace.go:171","msg":"trace[2011736432] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"131.88817ms","start":"2026-06-02T21:31:33.026494Z","end":"2026-06-02T21:31:33.158382Z","steps":["trace[2011736432] 'process raft request' (duration: 131.717515ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:33.158408Z","caller":"traceutil/trace.go:171","msg":"trace[229337965] linearizableReadLoop","detail":"{readStateIndex:5067; appliedIndex:5063; }","duration":"140.186835ms","start":"2026-06-02T21:31:33.018209Z","end":"2026-06-02T21:31:33.158396Z","steps":["trace[229337965] 'read index received' (duration: 13.086331ms)","trace[229337965] 'applied index is now lower than readState.Index' (duration: 127.099704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:33.158538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.312682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-02T21:31:33.158574Z","caller":"traceutil/trace.go:171","msg":"trace[2068696595] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4877; }","duration":"140.37563ms","start":"2026-06-02T21:31:33.018188Z","end":"2026-06-02T21:31:33.158564Z","steps":["trace[2068696595] 'agreement among raft nodes before linearized reading' (duration: 140.277183ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:34.029498Z","caller":"traceutil/trace.go:171","msg":"trace[872857034] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"158.341521ms","start":"2026-06-02T21:31:33.871129Z","end":"2026-06-02T21:31:34.029471Z","steps":["trace[872857034] 'process raft request' (duration: 116.751423ms)","trace[872857034] 'compare' (duration: 41.311935ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:32:12.515882Z","caller":"traceutil/trace.go:171","msg":"trace[1740472983] linearizableReadLoop","detail":"{readStateIndex:5427; appliedIndex:5426; }","duration":"137.744133ms","start":"2026-06-02T21:32:12.378113Z","end":"2026-06-02T21:32:12.515857Z","steps":["trace[1740472983] 'read index received' (duration: 117.128112ms)","trace[1740472983] 'applied index is now lower than readState.Index' (duration: 20.614831ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:32:12.516134Z","caller":"traceutil/trace.go:171","msg":"trace[2128593870] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"140.047501ms","start":"2026-06-02T21:32:12.376072Z","end":"2026-06-02T21:32:12.516119Z","steps":["trace[2128593870] 'process raft request' (duration: 119.249686ms)","trace[2128593870] 'compare' (duration: 20.269822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:32:12.516342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.064853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:32:12.516408Z","caller":"traceutil/trace.go:171","msg":"trace[1658007657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5227; }","duration":"118.16969ms","start":"2026-06-02T21:32:12.398222Z","end":"2026-06-02T21:32:12.516392Z","steps":["trace[1658007657] 'agreement among raft nodes before linearized reading' (duration: 118.064753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:32:12.516602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.47395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-06-02T21:32:12.516686Z","caller":"traceutil/trace.go:171","msg":"trace[1440470731] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5227; }","duration":"138.568377ms","start":"2026-06-02T21:32:12.378089Z","end":"2026-06-02T21:32:12.516657Z","steps":["trace[1440470731] 'agreement among raft nodes before linearized reading' (duration: 138.359563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:32:23.828429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.806231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:32:23.828551Z","caller":"traceutil/trace.go:171","msg":"trace[1863526780] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5308; }","duration":"138.971247ms","start":"2026-06-02T21:32:23.689553Z","end":"2026-06-02T21:32:23.828524Z","steps":["trace[1863526780] 'count revisions from in-memory index tree' (duration: 138.686006ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:32:24.188182Z","caller":"traceutil/trace.go:171","msg":"trace[16177844] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5515; }","duration":"266.993681ms","start":"2026-06-02T21:32:23.921165Z","end":"2026-06-02T21:32:24.188158Z","steps":["trace[16177844] 'read index received' (duration: 253.099468ms)","trace[16177844] 'applied index is now lower than readState.Index' (duration: 13.893173ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:32:24.188348Z","caller":"traceutil/trace.go:171","msg":"trace[1472134385] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"281.97746ms","start":"2026-06-02T21:32:23.906336Z","end":"2026-06-02T21:32:24.188314Z","steps":["trace[1472134385] 'process raft request' (duration: 267.97642ms)","trace[1472134385] 'compare' (duration: 13.526006ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:32:24.188486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.31077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T21:32:24.188534Z","caller":"traceutil/trace.go:171","msg":"trace[461114890] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5312; }","duration":"267.401287ms","start":"2026-06-02T21:32:23.92112Z","end":"2026-06-02T21:32:24.188521Z","steps":["trace[461114890] 'agreement among raft nodes before linearized reading' (duration: 267.134826ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:32:59.371127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-06-02T21:32:59.483275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"110.023199ms","hash":3233252424,"current-db-size-bytes":53293056,"current-db-size":"53 MB","current-db-size-in-use-bytes":29392896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-02T21:32:59.483331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3233252424,"revision":2879,"compact-revision":-1} {"level":"info","ts":"2026-06-02T21:37:59.379589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5584} {"level":"info","ts":"2026-06-02T21:37:59.493004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5584,"took":"112.227573ms","hash":271885166,"current-db-size-bytes":53293056,"current-db-size":"53 MB","current-db-size-in-use-bytes":18472960,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-02T21:37:59.493073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":271885166,"revision":5584,"compact-revision":2879}