{"level":"warn","ts":"2026-05-22T19:04:10.617495Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-22T19:04:10.617584Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-05-22T19:04:10.617652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-22T19:04:10.617664Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-22T19:04:10.617684Z","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-05-22T19:04:10.618238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-22T19:04:10.618319Z","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":16,"max-cpu-available":16,"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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-05-22T19:04:10.622598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.052341ms"} {"level":"info","ts":"2026-05-22T19:04:10.631471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-22T19:04:10.631593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-22T19:04:10.631627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-22T19:04:10.631643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-22T19:04:10.631661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-22T19:04:10.631716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-22T19:04:10.638726Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-22T19:04:10.641827Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-22T19:04:10.643396Z","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-05-22T19:04:10.645819Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-22T19:04:10.645927Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-22T19:04:10.646245Z","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-05-22T19:04:10.646468Z","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-05-22T19:04:10.6465Z","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-05-22T19:04:10.646982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-22T19:04:10.647199Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-22T19:04:10.648639Z","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-05-22T19:04:10.648749Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-22T19:04:10.64886Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-22T19:04:10.64893Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-22T19:04:10.648966Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-22T19:04:10.731995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-22T19:04:10.732071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-22T19:04:10.732112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-22T19:04:10.732131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-22T19:04:10.732145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-22T19:04:10.732169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-22T19:04:10.732195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-22T19:04:10.733245Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-22T19:04:10.733257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T19:04:10.733372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T19:04:10.733677Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-22T19:04:10.733765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-22T19:04:10.737602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-22T19:04:10.737845Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T19:04:10.738235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-22T19:04:10.738827Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T19:04:10.738985Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T19:04:10.7391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T19:04:35.785123Z","caller":"traceutil/trace.go:171","msg":"trace[1715931258] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"413.995692ms","start":"2026-05-22T19:04:35.371107Z","end":"2026-05-22T19:04:35.785102Z","steps":["trace[1715931258] 'read index received' (duration: 413.856119ms)","trace[1715931258] 'applied index is now lower than readState.Index' (duration: 138.213µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:04:35.785199Z","caller":"traceutil/trace.go:171","msg":"trace[1706605875] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"425.080163ms","start":"2026-05-22T19:04:35.360082Z","end":"2026-05-22T19:04:35.785162Z","steps":["trace[1706605875] 'process raft request' (duration: 424.895229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:35.785281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.158385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-22T19:04:35.785368Z","caller":"traceutil/trace.go:171","msg":"trace[2052920988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"414.299779ms","start":"2026-05-22T19:04:35.371059Z","end":"2026-05-22T19:04:35.785359Z","steps":["trace[2052920988] 'agreement among raft nodes before linearized reading' (duration: 414.158326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:35.785395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:35.371037Z","time spent":"414.351579ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-22T19:04:35.785867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:35.360037Z","time spent":"425.235906ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:04:53.870422Z","caller":"traceutil/trace.go:171","msg":"trace[1448117169] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"408.452176ms","start":"2026-05-22T19:04:53.461947Z","end":"2026-05-22T19:04:53.870399Z","steps":["trace[1448117169] 'process raft request' (duration: 404.20598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:53.870556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.123177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:04:53.870361Z","caller":"traceutil/trace.go:171","msg":"trace[935078678] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"403.899082ms","start":"2026-05-22T19:04:53.466422Z","end":"2026-05-22T19:04:53.870321Z","steps":["trace[935078678] 'read index received' (duration: 398.987772ms)","trace[935078678] 'applied index is now lower than readState.Index' (duration: 4.91012ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:04:53.870642Z","caller":"traceutil/trace.go:171","msg":"trace[1744683862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"404.225958ms","start":"2026-05-22T19:04:53.466391Z","end":"2026-05-22T19:04:53.870617Z","steps":["trace[1744683862] 'agreement among raft nodes before linearized reading' (duration: 404.091656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:53.870684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.466379Z","time spent":"404.29685ms","remote":"127.0.0.1:54312","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-05-22T19:04:53.870681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.461916Z","time spent":"408.568278ms","remote":"127.0.0.1:54384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:04:54.372135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.969159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T19:04:54.372248Z","caller":"traceutil/trace.go:171","msg":"trace[426115026] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"501.615721ms","start":"2026-05-22T19:04:53.870616Z","end":"2026-05-22T19:04:54.372232Z","steps":["trace[426115026] 'read index received' (duration: 150.229884ms)","trace[426115026] 'applied index is now lower than readState.Index' (duration: 351.384207ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:04:54.372283Z","caller":"traceutil/trace.go:171","msg":"trace[891325926] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"598.421171ms","start":"2026-05-22T19:04:53.773826Z","end":"2026-05-22T19:04:54.372247Z","steps":["trace[891325926] 'process raft request' (duration: 246.987462ms)","trace[891325926] 'compare' (duration: 350.698504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:04:54.372434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.773791Z","time spent":"598.565024ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:04:54.37247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.192512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7njg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-22T19:04:54.372522Z","caller":"traceutil/trace.go:171","msg":"trace[342645360] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7njg; range_end:; response_count:1; response_revision:591; }","duration":"521.269013ms","start":"2026-05-22T19:04:53.851239Z","end":"2026-05-22T19:04:54.372508Z","steps":["trace[342645360] 'agreement among raft nodes before linearized reading' (duration: 521.058259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:54.372554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.851219Z","time spent":"521.327315ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-q7njg\" "} {"level":"warn","ts":"2026-05-22T19:04:54.401615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.621719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-22T19:04:54.401622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.510863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-22T19:04:54.401682Z","caller":"traceutil/trace.go:171","msg":"trace[588086527] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:592; }","duration":"409.735181ms","start":"2026-05-22T19:04:53.991929Z","end":"2026-05-22T19:04:54.401665Z","steps":["trace[588086527] 'agreement among raft nodes before linearized reading' (duration: 409.550887ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:04:54.401695Z","caller":"traceutil/trace.go:171","msg":"trace[469672812] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"481.901698ms","start":"2026-05-22T19:04:53.919768Z","end":"2026-05-22T19:04:54.40167Z","steps":["trace[469672812] 'process raft request' (duration: 481.589182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:54.401731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.991863Z","time spent":"409.854054ms","remote":"127.0.0.1:54340","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.169\" "} {"level":"info","ts":"2026-05-22T19:04:54.401734Z","caller":"traceutil/trace.go:171","msg":"trace[1820837876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"486.604434ms","start":"2026-05-22T19:04:53.915069Z","end":"2026-05-22T19:04:54.401673Z","steps":["trace[1820837876] 'agreement among raft nodes before linearized reading' (duration: 486.445451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:54.401785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.915031Z","time spent":"486.740337ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-22T19:04:54.401842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.919759Z","time spent":"481.995ms","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-22T19:04:54.402144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.291477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:04:54.402224Z","caller":"traceutil/trace.go:171","msg":"trace[457712045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"528.94512ms","start":"2026-05-22T19:04:53.873265Z","end":"2026-05-22T19:04:54.40221Z","steps":["trace[457712045] 'agreement among raft nodes before linearized reading' (duration: 528.207025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:54.402262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.873253Z","time spent":"528.997872ms","remote":"127.0.0.1:54320","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-05-22T19:04:54.402349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.403384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-22T19:04:54.4025Z","caller":"traceutil/trace.go:171","msg":"trace[1881680352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"517.537907ms","start":"2026-05-22T19:04:53.88489Z","end":"2026-05-22T19:04:54.402428Z","steps":["trace[1881680352] 'agreement among raft nodes before linearized reading' (duration: 516.895205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:04:54.402778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:04:53.884872Z","time spent":"517.888684ms","remote":"127.0.0.1:54560","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-05-22T19:05:38.993736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.772131ms","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-05-22T19:05:38.993878Z","caller":"traceutil/trace.go:171","msg":"trace[623138416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1154; }","duration":"166.032636ms","start":"2026-05-22T19:05:38.827818Z","end":"2026-05-22T19:05:38.993851Z","steps":["trace[623138416] 'range keys from in-memory index tree' (duration: 165.462885ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:06:02.38078Z","caller":"traceutil/trace.go:171","msg":"trace[1536809904] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"106.901836ms","start":"2026-05-22T19:06:02.273853Z","end":"2026-05-22T19:06:02.380754Z","steps":["trace[1536809904] 'process raft request' (duration: 106.340025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.750883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828824491529615,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-22T19:06:26.87223Z","caller":"traceutil/trace.go:171","msg":"trace[1593037592] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"679.404814ms","start":"2026-05-22T19:06:26.192783Z","end":"2026-05-22T19:06:26.872188Z","steps":["trace[1593037592] 'process raft request' (duration: 610.460444ms)","trace[1593037592] 'compare' (duration: 68.464231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:06:26.872431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.192756Z","time spent":"679.570688ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:06:26.910294Z","caller":"traceutil/trace.go:171","msg":"trace[735864879] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1900; }","duration":"660.257107ms","start":"2026-05-22T19:06:26.25001Z","end":"2026-05-22T19:06:26.910267Z","steps":["trace[735864879] 'read index received' (duration: 553.227958ms)","trace[735864879] 'applied index is now lower than readState.Index' (duration: 107.028049ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:06:26.910333Z","caller":"traceutil/trace.go:171","msg":"trace[1201742854] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"541.528635ms","start":"2026-05-22T19:06:26.368765Z","end":"2026-05-22T19:06:26.910294Z","steps":["trace[1201742854] 'process raft request' (duration: 541.428572ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:06:26.91045Z","caller":"traceutil/trace.go:171","msg":"trace[148125352] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"386.575447ms","start":"2026-05-22T19:06:26.523847Z","end":"2026-05-22T19:06:26.910423Z","steps":["trace[148125352] 'process raft request' (duration: 386.385262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.368737Z","time spent":"541.670167ms","remote":"127.0.0.1:54560","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-05-22T19:06:26.91045Z","caller":"traceutil/trace.go:171","msg":"trace[1751541199] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"608.424203ms","start":"2026-05-22T19:06:26.301965Z","end":"2026-05-22T19:06:26.91039Z","steps":["trace[1751541199] 'process raft request' (duration: 608.079966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.523821Z","time spent":"386.67758ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:06:26.91062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.871979ms","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":"warn","ts":"2026-05-22T19:06:26.910654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.754571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:06:26.910674Z","caller":"traceutil/trace.go:171","msg":"trace[1251485530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1828; }","duration":"354.92762ms","start":"2026-05-22T19:06:26.555731Z","end":"2026-05-22T19:06:26.910659Z","steps":["trace[1251485530] 'agreement among raft nodes before linearized reading' (duration: 354.770297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.30194Z","time spent":"608.621727ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:06:26.910684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.463779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-22T19:06:26.910716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.555703Z","time spent":"355.005172ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-22T19:06:26.910719Z","caller":"traceutil/trace.go:171","msg":"trace[300907539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1828; }","duration":"443.850364ms","start":"2026-05-22T19:06:26.466851Z","end":"2026-05-22T19:06:26.910701Z","steps":["trace[300907539] 'agreement among raft nodes before linearized reading' (duration: 443.744072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.505024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-47dd10a3-949f-4da7-9ae0-8f84a9448e68\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-22T19:06:26.910743Z","caller":"traceutil/trace.go:171","msg":"trace[1069131969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1828; }","duration":"287.568971ms","start":"2026-05-22T19:06:26.623157Z","end":"2026-05-22T19:06:26.910726Z","steps":["trace[1069131969] 'agreement among raft nodes before linearized reading' (duration: 287.426088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.501273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-05-22T19:06:26.910823Z","caller":"traceutil/trace.go:171","msg":"trace[1115439681] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1828; }","duration":"605.648707ms","start":"2026-05-22T19:06:26.305142Z","end":"2026-05-22T19:06:26.910791Z","steps":["trace[1115439681] 'agreement among raft nodes before linearized reading' (duration: 605.347111ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:06:26.910765Z","caller":"traceutil/trace.go:171","msg":"trace[653398075] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-47dd10a3-949f-4da7-9ae0-8f84a9448e68; range_end:; response_count:1; response_revision:1828; }","duration":"660.796149ms","start":"2026-05-22T19:06:26.24996Z","end":"2026-05-22T19:06:26.910756Z","steps":["trace[653398075] 'agreement among raft nodes before linearized reading' (duration: 660.442272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:06:26.910877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.305121Z","time spent":"605.743489ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-05-22T19:06:26.91092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.249937Z","time spent":"660.973483ms","remote":"127.0.0.1:54494","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-47dd10a3-949f-4da7-9ae0-8f84a9448e68\" "} {"level":"warn","ts":"2026-05-22T19:06:26.910756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:06:26.46683Z","time spent":"443.919495ms","remote":"127.0.0.1:54312","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-05-22T19:06:27.026788Z","caller":"traceutil/trace.go:171","msg":"trace[1577889967] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"104.915186ms","start":"2026-05-22T19:06:26.921844Z","end":"2026-05-22T19:06:27.026759Z","steps":["trace[1577889967] 'process raft request' (duration: 96.881954ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:06:39.111292Z","caller":"traceutil/trace.go:171","msg":"trace[1470127106] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"161.756013ms","start":"2026-05-22T19:06:38.949513Z","end":"2026-05-22T19:06:39.111269Z","steps":["trace[1470127106] 'process raft request' (duration: 129.916811ms)","trace[1470127106] 'compare' (duration: 31.729039ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:07:15.372382Z","caller":"traceutil/trace.go:171","msg":"trace[1597842361] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"149.899615ms","start":"2026-05-22T19:07:15.222435Z","end":"2026-05-22T19:07:15.372334Z","steps":["trace[1597842361] 'process raft request' (duration: 149.64994ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:07:21.534944Z","caller":"traceutil/trace.go:171","msg":"trace[1875434725] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2265; }","duration":"104.285992ms","start":"2026-05-22T19:07:21.430631Z","end":"2026-05-22T19:07:21.534917Z","steps":["trace[1875434725] 'read index received' (duration: 104.16826ms)","trace[1875434725] 'applied index is now lower than readState.Index' (duration: 116.692µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:07:21.534996Z","caller":"traceutil/trace.go:171","msg":"trace[215191995] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"146.312501ms","start":"2026-05-22T19:07:21.388649Z","end":"2026-05-22T19:07:21.534962Z","steps":["trace[215191995] 'process raft request' (duration: 146.129367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:07:21.535356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.6895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-22T19:07:21.535521Z","caller":"traceutil/trace.go:171","msg":"trace[56826212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"104.899265ms","start":"2026-05-22T19:07:21.4306Z","end":"2026-05-22T19:07:21.535499Z","steps":["trace[56826212] 'agreement among raft nodes before linearized reading' (duration: 104.463896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:07:21.913549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.43572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T19:07:21.913957Z","caller":"traceutil/trace.go:171","msg":"trace[1350150098] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2266; }","duration":"252.586984ms","start":"2026-05-22T19:07:21.661349Z","end":"2026-05-22T19:07:21.913936Z","steps":["trace[1350150098] 'read index received' (duration: 33.381µs)","trace[1350150098] 'applied index is now lower than readState.Index' (duration: 252.552363ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:07:21.914132Z","caller":"traceutil/trace.go:171","msg":"trace[2062092506] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"374.112439ms","start":"2026-05-22T19:07:21.540006Z","end":"2026-05-22T19:07:21.914119Z","steps":["trace[2062092506] 'process raft request' (duration: 373.636529ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:07:21.914138Z","caller":"traceutil/trace.go:171","msg":"trace[763741084] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"372.997816ms","start":"2026-05-22T19:07:21.541117Z","end":"2026-05-22T19:07:21.914115Z","steps":["trace[763741084] 'process raft request' (duration: 372.73985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:07:21.914331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:07:21.539992Z","time spent":"374.288752ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:07:21.914336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:07:21.541104Z","time spent":"373.107459ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:07:21.91453Z","caller":"traceutil/trace.go:171","msg":"trace[2117256673] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"374.431466ms","start":"2026-05-22T19:07:21.539549Z","end":"2026-05-22T19:07:21.91398Z","steps":["trace[2117256673] 'process raft request' (duration: 56.479365ms)","trace[2117256673] 'compare' (duration: 317.139624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:07:21.914749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:07:21.539531Z","time spent":"375.187481ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:07:21.914619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.247727ms","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-05-22T19:07:21.914877Z","caller":"traceutil/trace.go:171","msg":"trace[428335759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2182; }","duration":"253.564864ms","start":"2026-05-22T19:07:21.661302Z","end":"2026-05-22T19:07:21.914867Z","steps":["trace[428335759] 'agreement among raft nodes before linearized reading' (duration: 252.741877ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:07:22.131245Z","caller":"traceutil/trace.go:171","msg":"trace[1333885137] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"212.489554ms","start":"2026-05-22T19:07:21.918726Z","end":"2026-05-22T19:07:22.131216Z","steps":["trace[1333885137] 'process raft request' (duration: 212.31183ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:07:22.149708Z","caller":"traceutil/trace.go:171","msg":"trace[852920213] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"230.713886ms","start":"2026-05-22T19:07:21.918968Z","end":"2026-05-22T19:07:22.149682Z","steps":["trace[852920213] 'process raft request' (duration: 230.567493ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:07:56.491362Z","caller":"traceutil/trace.go:171","msg":"trace[475253620] linearizableReadLoop","detail":"{readStateIndex:2644; appliedIndex:2643; }","duration":"187.154333ms","start":"2026-05-22T19:07:56.304183Z","end":"2026-05-22T19:07:56.491338Z","steps":["trace[475253620] 'read index received' (duration: 186.946628ms)","trace[475253620] 'applied index is now lower than readState.Index' (duration: 206.455µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:07:56.491438Z","caller":"traceutil/trace.go:171","msg":"trace[1612778758] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"202.35962ms","start":"2026-05-22T19:07:56.289037Z","end":"2026-05-22T19:07:56.491396Z","steps":["trace[1612778758] 'process raft request' (duration: 202.098735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:07:56.491598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.404158ms","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-05-22T19:07:56.491637Z","caller":"traceutil/trace.go:171","msg":"trace[540228424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2544; }","duration":"187.481479ms","start":"2026-05-22T19:07:56.304144Z","end":"2026-05-22T19:07:56.491625Z","steps":["trace[540228424] 'agreement among raft nodes before linearized reading' (duration: 187.338216ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:08:30.309161Z","caller":"traceutil/trace.go:171","msg":"trace[230245070] linearizableReadLoop","detail":"{readStateIndex:2856; appliedIndex:2855; }","duration":"129.83272ms","start":"2026-05-22T19:08:30.179296Z","end":"2026-05-22T19:08:30.309129Z","steps":["trace[230245070] 'read index received' (duration: 124.524219ms)","trace[230245070] 'applied index is now lower than readState.Index' (duration: 5.306991ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:08:30.30917Z","caller":"traceutil/trace.go:171","msg":"trace[2056038342] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"158.9152ms","start":"2026-05-22T19:08:30.150219Z","end":"2026-05-22T19:08:30.309134Z","steps":["trace[2056038342] 'process raft request' (duration: 153.697991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:08:30.309345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.025954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:08:30.309381Z","caller":"traceutil/trace.go:171","msg":"trace[1441835781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2748; }","duration":"130.105206ms","start":"2026-05-22T19:08:30.179264Z","end":"2026-05-22T19:08:30.309369Z","steps":["trace[1441835781] 'agreement among raft nodes before linearized reading' (duration: 130.022914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:08:30.639616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.66886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-22T19:08:30.639675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.836342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:08:30.639719Z","caller":"traceutil/trace.go:171","msg":"trace[383895436] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2751; }","duration":"215.860613ms","start":"2026-05-22T19:08:30.423838Z","end":"2026-05-22T19:08:30.639699Z","steps":["trace[383895436] 'range keys from in-memory index tree' (duration: 215.403934ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:08:30.639758Z","caller":"traceutil/trace.go:171","msg":"trace[472758910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"172.998015ms","start":"2026-05-22T19:08:30.466741Z","end":"2026-05-22T19:08:30.639739Z","steps":["trace[472758910] 'range keys from in-memory index tree' (duration: 172.751421ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:20.171555Z","caller":"traceutil/trace.go:171","msg":"trace[583448115] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"114.590905ms","start":"2026-05-22T19:12:20.056915Z","end":"2026-05-22T19:12:20.171506Z","steps":["trace[583448115] 'process raft request' (duration: 114.404471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:20.171739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.279281ms","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-05-22T19:12:20.171783Z","caller":"traceutil/trace.go:171","msg":"trace[318175933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4686; }","duration":"102.408114ms","start":"2026-05-22T19:12:20.069366Z","end":"2026-05-22T19:12:20.171774Z","steps":["trace[318175933] 'agreement among raft nodes before linearized reading' (duration: 102.238661ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:20.171798Z","caller":"traceutil/trace.go:171","msg":"trace[178001745] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"111.081805ms","start":"2026-05-22T19:12:20.0607Z","end":"2026-05-22T19:12:20.171781Z","steps":["trace[178001745] 'process raft request' (duration: 110.727138ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:20.171489Z","caller":"traceutil/trace.go:171","msg":"trace[806965007] linearizableReadLoop","detail":"{readStateIndex:4868; appliedIndex:4865; }","duration":"102.007206ms","start":"2026-05-22T19:12:20.069461Z","end":"2026-05-22T19:12:20.171469Z","steps":["trace[806965007] 'read index received' (duration: 44.989904ms)","trace[806965007] 'applied index is now lower than readState.Index' (duration: 57.016412ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:12:25.683748Z","caller":"traceutil/trace.go:171","msg":"trace[868715509] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"147.671661ms","start":"2026-05-22T19:12:25.536021Z","end":"2026-05-22T19:12:25.683692Z","steps":["trace[868715509] 'process raft request' (duration: 49.37825ms)","trace[868715509] 'compare' (duration: 98.032466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:29.805731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.045732ms","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-05-22T19:12:29.805835Z","caller":"traceutil/trace.go:171","msg":"trace[1014091278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4952; }","duration":"258.194535ms","start":"2026-05-22T19:12:29.547622Z","end":"2026-05-22T19:12:29.805817Z","steps":["trace[1014091278] 'range keys from in-memory index tree' (duration: 257.873869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:29.806082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.397534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-22T19:12:29.806118Z","caller":"traceutil/trace.go:171","msg":"trace[764325892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4952; }","duration":"223.478776ms","start":"2026-05-22T19:12:29.58263Z","end":"2026-05-22T19:12:29.806109Z","steps":["trace[764325892] 'range keys from in-memory index tree' (duration: 223.275593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:29.806208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.485314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db545493-c34d-4419-b579-ac1f8d7ccb3a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-22T19:12:29.806314Z","caller":"traceutil/trace.go:171","msg":"trace[297146004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db545493-c34d-4419-b579-ac1f8d7ccb3a; range_end:; response_count:1; response_revision:4952; }","duration":"104.633267ms","start":"2026-05-22T19:12:29.701653Z","end":"2026-05-22T19:12:29.806286Z","steps":["trace[297146004] 'range keys from in-memory index tree' (duration: 104.25466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:29.806616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.688249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-05-22T19:12:29.806657Z","caller":"traceutil/trace.go:171","msg":"trace[1328832482] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4952; }","duration":"163.76403ms","start":"2026-05-22T19:12:29.642881Z","end":"2026-05-22T19:12:29.806645Z","steps":["trace[1328832482] 'range keys from in-memory index tree' (duration: 163.583467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:29.807046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.444542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T19:12:29.80709Z","caller":"traceutil/trace.go:171","msg":"trace[410767036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4952; }","duration":"109.512174ms","start":"2026-05-22T19:12:29.697568Z","end":"2026-05-22T19:12:29.80708Z","steps":["trace[410767036] 'range keys from in-memory index tree' (duration: 109.30363ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:42.3565Z","caller":"traceutil/trace.go:171","msg":"trace[204911910] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"104.362942ms","start":"2026-05-22T19:12:42.252112Z","end":"2026-05-22T19:12:42.356475Z","steps":["trace[204911910] 'process raft request' (duration: 104.178818ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:42.356467Z","caller":"traceutil/trace.go:171","msg":"trace[235929468] linearizableReadLoop","detail":"{readStateIndex:5386; appliedIndex:5385; }","duration":"101.405313ms","start":"2026-05-22T19:12:42.255044Z","end":"2026-05-22T19:12:42.356449Z","steps":["trace[235929468] 'read index received' (duration: 101.25412ms)","trace[235929468] 'applied index is now lower than readState.Index' (duration: 150.403µs)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:42.356731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.671798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131091"} {"level":"info","ts":"2026-05-22T19:12:42.3568Z","caller":"traceutil/trace.go:171","msg":"trace[606087920] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5188; }","duration":"101.76098ms","start":"2026-05-22T19:12:42.255026Z","end":"2026-05-22T19:12:42.356787Z","steps":["trace[606087920] 'agreement among raft nodes before linearized reading' (duration: 101.517205ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:42.478218Z","caller":"traceutil/trace.go:171","msg":"trace[1334637524] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"118.334629ms","start":"2026-05-22T19:12:42.359847Z","end":"2026-05-22T19:12:42.478182Z","steps":["trace[1334637524] 'process raft request' (duration: 108.505565ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:42.478317Z","caller":"traceutil/trace.go:171","msg":"trace[1533285672] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5387; }","duration":"110.511584ms","start":"2026-05-22T19:12:42.367789Z","end":"2026-05-22T19:12:42.4783Z","steps":["trace[1533285672] 'read index received' (duration: 100.468835ms)","trace[1533285672] 'applied index is now lower than readState.Index' (duration: 10.041819ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:12:42.478379Z","caller":"traceutil/trace.go:171","msg":"trace[1489684772] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"117.731937ms","start":"2026-05-22T19:12:42.36061Z","end":"2026-05-22T19:12:42.478342Z","steps":["trace[1489684772] 'process raft request' (duration: 117.339788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:42.478552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.120926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-mpbv5\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-22T19:12:42.4786Z","caller":"traceutil/trace.go:171","msg":"trace[1342532066] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-mpbv5; range_end:; response_count:1; response_revision:5191; }","duration":"119.222877ms","start":"2026-05-22T19:12:42.359362Z","end":"2026-05-22T19:12:42.478585Z","steps":["trace[1342532066] 'agreement among raft nodes before linearized reading' (duration: 119.047383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:43.037827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.358185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T19:12:43.037932Z","caller":"traceutil/trace.go:171","msg":"trace[611001393] linearizableReadLoop","detail":"{readStateIndex:5400; appliedIndex:5399; }","duration":"179.804019ms","start":"2026-05-22T19:12:42.85811Z","end":"2026-05-22T19:12:43.037914Z","steps":["trace[611001393] 'read index received' (duration: 27.77µs)","trace[611001393] 'applied index is now lower than readState.Index' (duration: 179.775229ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:12:43.038015Z","caller":"traceutil/trace.go:171","msg":"trace[2098017035] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"301.062905ms","start":"2026-05-22T19:12:42.736926Z","end":"2026-05-22T19:12:43.037989Z","steps":["trace[2098017035] 'process raft request' (duration: 20.468526ms)","trace[2098017035] 'compare' (duration: 280.240992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:43.038066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.954882ms","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-05-22T19:12:43.038093Z","caller":"traceutil/trace.go:171","msg":"trace[133196177] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5202; }","duration":"180.014793ms","start":"2026-05-22T19:12:42.85807Z","end":"2026-05-22T19:12:43.038085Z","steps":["trace[133196177] 'agreement among raft nodes before linearized reading' (duration: 179.889771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:43.03811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:42.736902Z","time spent":"301.161338ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:12:50.452841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.003542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:4474"} {"level":"info","ts":"2026-05-22T19:12:50.452918Z","caller":"traceutil/trace.go:171","msg":"trace[1543757563] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5379; }","duration":"118.142025ms","start":"2026-05-22T19:12:50.334762Z","end":"2026-05-22T19:12:50.452904Z","steps":["trace[1543757563] 'range keys from in-memory index tree' (duration: 117.8944ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:50.693148Z","caller":"traceutil/trace.go:171","msg":"trace[221798344] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"231.18203ms","start":"2026-05-22T19:12:50.461943Z","end":"2026-05-22T19:12:50.693125Z","steps":["trace[221798344] 'read index received' (duration: 214.658451ms)","trace[221798344] 'applied index is now lower than readState.Index' (duration: 16.522709ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:12:50.693232Z","caller":"traceutil/trace.go:171","msg":"trace[321251122] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"231.756941ms","start":"2026-05-22T19:12:50.461445Z","end":"2026-05-22T19:12:50.693202Z","steps":["trace[321251122] 'process raft request' (duration: 215.141251ms)","trace[321251122] 'compare' (duration: 16.437187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:50.69336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.401214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-765091ca-ff57-4548-8613-423d292e0f6c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-22T19:12:50.693394Z","caller":"traceutil/trace.go:171","msg":"trace[1161480565] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-765091ca-ff57-4548-8613-423d292e0f6c; range_end:; response_count:1; response_revision:5381; }","duration":"231.475916ms","start":"2026-05-22T19:12:50.461908Z","end":"2026-05-22T19:12:50.693384Z","steps":["trace[1161480565] 'agreement among raft nodes before linearized reading' (duration: 231.329083ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:50.708343Z","caller":"traceutil/trace.go:171","msg":"trace[133078544] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"206.03717ms","start":"2026-05-22T19:12:50.502289Z","end":"2026-05-22T19:12:50.708326Z","steps":["trace[133078544] 'process raft request' (duration: 205.944028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:50.708582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.765298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:12:50.708637Z","caller":"traceutil/trace.go:171","msg":"trace[151409366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5382; }","duration":"242.971423ms","start":"2026-05-22T19:12:50.465653Z","end":"2026-05-22T19:12:50.708624Z","steps":["trace[151409366] 'agreement among raft nodes before linearized reading' (duration: 242.740528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.05554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.700044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T19:12:51.055817Z","caller":"traceutil/trace.go:171","msg":"trace[1250330954] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"356.68838ms","start":"2026-05-22T19:12:50.699111Z","end":"2026-05-22T19:12:51.055799Z","steps":["trace[1250330954] 'process raft request' (duration: 356.616029ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:51.055843Z","caller":"traceutil/trace.go:171","msg":"trace[726890245] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"356.834593ms","start":"2026-05-22T19:12:50.69898Z","end":"2026-05-22T19:12:51.055815Z","steps":["trace[726890245] 'process raft request' (duration: 356.649769ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:51.055907Z","caller":"traceutil/trace.go:171","msg":"trace[674818600] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"357.068717ms","start":"2026-05-22T19:12:50.698788Z","end":"2026-05-22T19:12:51.055857Z","steps":["trace[674818600] 'process raft request' (duration: 220.976376ms)","trace[674818600] 'compare' (duration: 135.597062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:51.055947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.699106Z","time spent":"356.803353ms","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1776,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:12:51.056005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.698756Z","time spent":"357.20202ms","remote":"127.0.0.1:54470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1137,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:12:51.056028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.698962Z","time spent":"356.980566ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:12:51.05611Z","caller":"traceutil/trace.go:171","msg":"trace[132983431] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"357.006728ms","start":"2026-05-22T19:12:50.699065Z","end":"2026-05-22T19:12:51.056072Z","steps":["trace[132983431] 'process raft request' (duration: 356.618309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.056276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.69906Z","time spent":"357.13566ms","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1677,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:12:51.056296Z","caller":"traceutil/trace.go:171","msg":"trace[1735170715] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"356.834684ms","start":"2026-05-22T19:12:50.69945Z","end":"2026-05-22T19:12:51.056284Z","steps":["trace[1735170715] 'process raft request' (duration: 356.311733ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:12:51.056314Z","caller":"traceutil/trace.go:171","msg":"trace[1103621330] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5585; }","duration":"347.927707ms","start":"2026-05-22T19:12:50.708374Z","end":"2026-05-22T19:12:51.056302Z","steps":["trace[1103621330] 'read index received' (duration: 211.363606ms)","trace[1103621330] 'applied index is now lower than readState.Index' (duration: 136.563131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:12:51.056352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.699426Z","time spent":"356.895286ms","remote":"127.0.0.1:54470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:12:51.05648Z","caller":"traceutil/trace.go:171","msg":"trace[1186486246] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"353.388724ms","start":"2026-05-22T19:12:50.703071Z","end":"2026-05-22T19:12:51.056459Z","steps":["trace[1186486246] 'process raft request' (duration: 353.065218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.056572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.703062Z","time spent":"353.468037ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3884,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T19:12:51.056583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.646489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-765091ca-ff57-4548-8613-423d292e0f6c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-22T19:12:51.056669Z","caller":"traceutil/trace.go:171","msg":"trace[1484501300] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-765091ca-ff57-4548-8613-423d292e0f6c; range_end:; response_count:1; response_revision:5388; }","duration":"359.774932ms","start":"2026-05-22T19:12:50.69688Z","end":"2026-05-22T19:12:51.056655Z","steps":["trace[1484501300] 'agreement among raft nodes before linearized reading' (duration: 359.610179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.056735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.696859Z","time spent":"359.866434ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-765091ca-ff57-4548-8613-423d292e0f6c\" "} {"level":"info","ts":"2026-05-22T19:12:51.059846Z","caller":"traceutil/trace.go:171","msg":"trace[492496358] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"347.492448ms","start":"2026-05-22T19:12:50.712329Z","end":"2026-05-22T19:12:51.059821Z","steps":["trace[492496358] 'process raft request' (duration: 347.355245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.060015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:12:50.712307Z","time spent":"347.613549ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:12:51.060107Z","caller":"traceutil/trace.go:171","msg":"trace[180167747] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"269.110712ms","start":"2026-05-22T19:12:50.790967Z","end":"2026-05-22T19:12:51.060078Z","steps":["trace[180167747] 'process raft request' (duration: 268.824816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:12:51.060248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.133528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:12:51.060357Z","caller":"traceutil/trace.go:171","msg":"trace[977142553] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5390; }","duration":"234.271931ms","start":"2026-05-22T19:12:50.82607Z","end":"2026-05-22T19:12:51.060341Z","steps":["trace[977142553] 'agreement among raft nodes before linearized reading' (duration: 233.950184ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:13:01.644187Z","caller":"traceutil/trace.go:171","msg":"trace[1959100048] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5723; }","duration":"108.456163ms","start":"2026-05-22T19:13:01.535707Z","end":"2026-05-22T19:13:01.644163Z","steps":["trace[1959100048] 'read index received' (duration: 77.347246ms)","trace[1959100048] 'applied index is now lower than readState.Index' (duration: 31.107767ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:13:01.644164Z","caller":"traceutil/trace.go:171","msg":"trace[24321245] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"139.679703ms","start":"2026-05-22T19:13:01.504452Z","end":"2026-05-22T19:13:01.644132Z","steps":["trace[24321245] 'process raft request' (duration: 108.503724ms)","trace[24321245] 'compare' (duration: 30.893833ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:13:01.644216Z","caller":"traceutil/trace.go:171","msg":"trace[631562191] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"139.127242ms","start":"2026-05-22T19:13:01.505074Z","end":"2026-05-22T19:13:01.644202Z","steps":["trace[631562191] 'process raft request' (duration: 138.970729ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:13:01.644326Z","caller":"traceutil/trace.go:171","msg":"trace[698117469] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"138.922507ms","start":"2026-05-22T19:13:01.505369Z","end":"2026-05-22T19:13:01.644292Z","steps":["trace[698117469] 'process raft request' (duration: 138.730573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:13:01.644367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.656637ms","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-05-22T19:13:01.644399Z","caller":"traceutil/trace.go:171","msg":"trace[581099457] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5521; }","duration":"108.734279ms","start":"2026-05-22T19:13:01.535656Z","end":"2026-05-22T19:13:01.64439Z","steps":["trace[581099457] 'agreement among raft nodes before linearized reading' (duration: 108.614376ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:14:11.012813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-05-22T19:14:11.09015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"75.534666ms","hash":2767899133,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":34430976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-22T19:14:11.090237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767899133,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-05-22T19:18:55.285577Z","caller":"traceutil/trace.go:171","msg":"trace[86313622] linearizableReadLoop","detail":"{readStateIndex:8477; appliedIndex:8476; }","duration":"114.978357ms","start":"2026-05-22T19:18:55.170573Z","end":"2026-05-22T19:18:55.285551Z","steps":["trace[86313622] 'read index received' (duration: 114.789083ms)","trace[86313622] 'applied index is now lower than readState.Index' (duration: 188.204µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:18:55.285663Z","caller":"traceutil/trace.go:171","msg":"trace[1940382073] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"403.573428ms","start":"2026-05-22T19:18:54.882044Z","end":"2026-05-22T19:18:55.285617Z","steps":["trace[1940382073] 'process raft request' (duration: 403.342764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:18:55.285825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.214352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-22T19:18:55.285909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:18:54.882009Z","time spent":"403.759252ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:18:55.285928Z","caller":"traceutil/trace.go:171","msg":"trace[307165882] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8190; }","duration":"115.372535ms","start":"2026-05-22T19:18:55.170531Z","end":"2026-05-22T19:18:55.285904Z","steps":["trace[307165882] 'agreement among raft nodes before linearized reading' (duration: 115.189041ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:08.201202Z","caller":"traceutil/trace.go:171","msg":"trace[1902336611] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"225.938781ms","start":"2026-05-22T19:19:07.975209Z","end":"2026-05-22T19:19:08.201148Z","steps":["trace[1902336611] 'process raft request' (duration: 221.909516ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:08.238676Z","caller":"traceutil/trace.go:171","msg":"trace[1830409177] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"263.0028ms","start":"2026-05-22T19:19:07.975605Z","end":"2026-05-22T19:19:08.238608Z","steps":["trace[1830409177] 'process raft request' (duration: 262.768975ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:08.238743Z","caller":"traceutil/trace.go:171","msg":"trace[1732264235] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"261.796216ms","start":"2026-05-22T19:19:07.976894Z","end":"2026-05-22T19:19:08.23869Z","steps":["trace[1732264235] 'process raft request' (duration: 261.655483ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:08.238808Z","caller":"traceutil/trace.go:171","msg":"trace[915960249] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"117.195723ms","start":"2026-05-22T19:19:08.121584Z","end":"2026-05-22T19:19:08.23878Z","steps":["trace[915960249] 'process raft request' (duration: 117.00889ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:08.239091Z","caller":"traceutil/trace.go:171","msg":"trace[1519652110] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"261.812965ms","start":"2026-05-22T19:19:07.976822Z","end":"2026-05-22T19:19:08.238634Z","steps":["trace[1519652110] 'process raft request' (duration: 261.676663ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:11.019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6100} {"level":"info","ts":"2026-05-22T19:19:11.123418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6100,"took":"102.352525ms","hash":1459002561,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":23810048,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-22T19:19:11.123519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1459002561,"revision":6100,"compact-revision":2971} {"level":"warn","ts":"2026-05-22T19:19:36.523002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.981765ms","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-05-22T19:19:36.523077Z","caller":"traceutil/trace.go:171","msg":"trace[1023580837] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8461; }","duration":"362.105418ms","start":"2026-05-22T19:19:36.160958Z","end":"2026-05-22T19:19:36.523063Z","steps":["trace[1023580837] 'range keys from in-memory index tree' (duration: 361.801031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.523109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.160935Z","time spent":"362.165029ms","remote":"127.0.0.1:54494","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-05-22T19:19:36.702325Z","caller":"traceutil/trace.go:171","msg":"trace[1283710172] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"367.515721ms","start":"2026-05-22T19:19:36.334778Z","end":"2026-05-22T19:19:36.702294Z","steps":["trace[1283710172] 'process raft request' (duration: 367.356846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.702512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.334752Z","time spent":"367.670913ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T19:19:36.703319Z","caller":"traceutil/trace.go:171","msg":"trace[390550893] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8761; }","duration":"363.938615ms","start":"2026-05-22T19:19:36.339368Z","end":"2026-05-22T19:19:36.703307Z","steps":["trace[390550893] 'read index received' (duration: 363.933765ms)","trace[390550893] 'applied index is now lower than readState.Index' (duration: 3.82µs)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:19:36.703689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.311413ms","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-05-22T19:19:36.703737Z","caller":"traceutil/trace.go:171","msg":"trace[2107054550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8462; }","duration":"364.399594ms","start":"2026-05-22T19:19:36.339328Z","end":"2026-05-22T19:19:36.703727Z","steps":["trace[2107054550] 'agreement among raft nodes before linearized reading' (duration: 364.063917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.70377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.339314Z","time spent":"364.449406ms","remote":"127.0.0.1:54560","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-05-22T19:19:36.705331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.5534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T19:19:36.705435Z","caller":"traceutil/trace.go:171","msg":"trace[713664060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8464; }","duration":"340.710433ms","start":"2026-05-22T19:19:36.364701Z","end":"2026-05-22T19:19:36.705411Z","steps":["trace[713664060] 'agreement among raft nodes before linearized reading' (duration: 340.406827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.705489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.364678Z","time spent":"340.799346ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-22T19:19:36.705926Z","caller":"traceutil/trace.go:171","msg":"trace[1430347134] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"164.880444ms","start":"2026-05-22T19:19:36.541029Z","end":"2026-05-22T19:19:36.705909Z","steps":["trace[1430347134] 'process raft request' (duration: 163.859902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.706317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.64837ms","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-05-22T19:19:36.70637Z","caller":"traceutil/trace.go:171","msg":"trace[1504127852] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8464; }","duration":"179.743521ms","start":"2026-05-22T19:19:36.526613Z","end":"2026-05-22T19:19:36.706356Z","steps":["trace[1504127852] 'agreement among raft nodes before linearized reading' (duration: 179.600308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.70658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.292834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T19:19:36.706628Z","caller":"traceutil/trace.go:171","msg":"trace[2132409116] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8464; }","duration":"300.365915ms","start":"2026-05-22T19:19:36.406251Z","end":"2026-05-22T19:19:36.706617Z","steps":["trace[2132409116] 'agreement among raft nodes before linearized reading' (duration: 300.283483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.706665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.406242Z","time spent":"300.411496ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-22T19:19:36.706889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.264857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtwkv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-22T19:19:36.706938Z","caller":"traceutil/trace.go:171","msg":"trace[930408469] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtwkv; range_end:; response_count:1; response_revision:8464; }","duration":"305.314808ms","start":"2026-05-22T19:19:36.401611Z","end":"2026-05-22T19:19:36.706926Z","steps":["trace[930408469] 'agreement among raft nodes before linearized reading' (duration: 305.229647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.706979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.401545Z","time spent":"305.42434ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-xtwkv\" "} {"level":"warn","ts":"2026-05-22T19:19:36.707207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.012044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-22T19:19:36.707256Z","caller":"traceutil/trace.go:171","msg":"trace[1009153118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8464; }","duration":"334.065995ms","start":"2026-05-22T19:19:36.373179Z","end":"2026-05-22T19:19:36.707245Z","steps":["trace[1009153118] 'agreement among raft nodes before linearized reading' (duration: 333.944102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.707294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.37313Z","time spent":"334.155777ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-22T19:19:36.707501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.569287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-22T19:19:36.707544Z","caller":"traceutil/trace.go:171","msg":"trace[649045279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8464; }","duration":"336.679429ms","start":"2026-05-22T19:19:36.370853Z","end":"2026-05-22T19:19:36.707533Z","steps":["trace[649045279] 'agreement among raft nodes before linearized reading' (duration: 336.574537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:36.707574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T19:19:36.370821Z","time spent":"336.744851ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-22T19:19:45.65071Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002288.wal"} {"level":"info","ts":"2026-05-22T19:19:48.751743Z","caller":"traceutil/trace.go:171","msg":"trace[63952674] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"217.396653ms","start":"2026-05-22T19:19:48.534307Z","end":"2026-05-22T19:19:48.751704Z","steps":["trace[63952674] 'process raft request' (duration: 217.163338ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.966556Z","caller":"traceutil/trace.go:171","msg":"trace[2095735157] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"208.00756ms","start":"2026-05-22T19:19:48.758525Z","end":"2026-05-22T19:19:48.966533Z","steps":["trace[2095735157] 'process raft request' (duration: 207.858237ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.98912Z","caller":"traceutil/trace.go:171","msg":"trace[1232500760] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"224.573272ms","start":"2026-05-22T19:19:48.76452Z","end":"2026-05-22T19:19:48.989093Z","steps":["trace[1232500760] 'process raft request' (duration: 224.363757ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.989411Z","caller":"traceutil/trace.go:171","msg":"trace[90682531] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"157.359976ms","start":"2026-05-22T19:19:48.832024Z","end":"2026-05-22T19:19:48.989384Z","steps":["trace[90682531] 'process raft request' (duration: 157.024709ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.989524Z","caller":"traceutil/trace.go:171","msg":"trace[1234669873] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8871; }","duration":"218.439635ms","start":"2026-05-22T19:19:48.771059Z","end":"2026-05-22T19:19:48.989499Z","steps":["trace[1234669873] 'read index received' (duration: 195.41988ms)","trace[1234669873] 'applied index is now lower than readState.Index' (duration: 23.018895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T19:19:48.98979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.725067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-22T19:19:48.989835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.010422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-22T19:19:48.98984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.776692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-22T19:19:48.989881Z","caller":"traceutil/trace.go:171","msg":"trace[512534759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8573; }","duration":"163.081073ms","start":"2026-05-22T19:19:48.826786Z","end":"2026-05-22T19:19:48.989867Z","steps":["trace[512534759] 'agreement among raft nodes before linearized reading' (duration: 162.93329ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.989887Z","caller":"traceutil/trace.go:171","msg":"trace[316479318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8573; }","duration":"218.868654ms","start":"2026-05-22T19:19:48.771006Z","end":"2026-05-22T19:19:48.989874Z","steps":["trace[316479318] 'agreement among raft nodes before linearized reading' (duration: 218.622679ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:19:48.989852Z","caller":"traceutil/trace.go:171","msg":"trace[1916461144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8573; }","duration":"163.842229ms","start":"2026-05-22T19:19:48.825995Z","end":"2026-05-22T19:19:48.989837Z","steps":["trace[1916461144] 'agreement among raft nodes before linearized reading' (duration: 163.662645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:48.989953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.640825ms","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-05-22T19:19:48.990023Z","caller":"traceutil/trace.go:171","msg":"trace[44308413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8573; }","duration":"163.753937ms","start":"2026-05-22T19:19:48.826252Z","end":"2026-05-22T19:19:48.990006Z","steps":["trace[44308413] 'agreement among raft nodes before linearized reading' (duration: 163.557743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:19:48.989859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.131612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtwkv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-22T19:19:48.990118Z","caller":"traceutil/trace.go:171","msg":"trace[1956526583] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtwkv; range_end:; response_count:1; response_revision:8573; }","duration":"158.429907ms","start":"2026-05-22T19:19:48.831666Z","end":"2026-05-22T19:19:48.990096Z","steps":["trace[1956526583] 'agreement among raft nodes before linearized reading' (duration: 158.026409ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.495838Z","caller":"traceutil/trace.go:171","msg":"trace[2097827397] transaction","detail":"{read_only:false; response_revision:9314; number_of_response:1; }","duration":"261.832947ms","start":"2026-05-22T19:21:05.233979Z","end":"2026-05-22T19:21:05.495812Z","steps":["trace[2097827397] 'process raft request' (duration: 261.638313ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.504116Z","caller":"traceutil/trace.go:171","msg":"trace[1261862755] linearizableReadLoop","detail":"{readStateIndex:9640; appliedIndex:9637; }","duration":"200.49761ms","start":"2026-05-22T19:21:05.303592Z","end":"2026-05-22T19:21:05.50409Z","steps":["trace[1261862755] 'read index received' (duration: 192.1378ms)","trace[1261862755] 'applied index is now lower than readState.Index' (duration: 8.3586ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:21:05.504191Z","caller":"traceutil/trace.go:171","msg":"trace[564543391] transaction","detail":"{read_only:false; response_revision:9315; number_of_response:1; }","duration":"255.669262ms","start":"2026-05-22T19:21:05.248479Z","end":"2026-05-22T19:21:05.504148Z","steps":["trace[564543391] 'process raft request' (duration: 255.423777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:21:05.504337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.455014ms","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-05-22T19:21:05.504239Z","caller":"traceutil/trace.go:171","msg":"trace[1789794882] transaction","detail":"{read_only:false; response_revision:9316; number_of_response:1; }","duration":"251.61572ms","start":"2026-05-22T19:21:05.252611Z","end":"2026-05-22T19:21:05.504226Z","steps":["trace[1789794882] 'process raft request' (duration: 251.433796ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.504382Z","caller":"traceutil/trace.go:171","msg":"trace[1385548617] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9316; }","duration":"101.578717ms","start":"2026-05-22T19:21:05.402792Z","end":"2026-05-22T19:21:05.504371Z","steps":["trace[1385548617] 'agreement among raft nodes before linearized reading' (duration: 101.477795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:21:05.504427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.608616ms","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-05-22T19:21:05.505014Z","caller":"traceutil/trace.go:171","msg":"trace[162975266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9316; }","duration":"186.204838ms","start":"2026-05-22T19:21:05.318757Z","end":"2026-05-22T19:21:05.504962Z","steps":["trace[162975266] 'agreement among raft nodes before linearized reading' (duration: 185.504764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:21:05.504484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.856467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T19:21:05.505165Z","caller":"traceutil/trace.go:171","msg":"trace[850840519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:9316; }","duration":"201.604162ms","start":"2026-05-22T19:21:05.303538Z","end":"2026-05-22T19:21:05.505142Z","steps":["trace[850840519] 'agreement among raft nodes before linearized reading' (duration: 200.801836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:21:05.504613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.826531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-22T19:21:05.505353Z","caller":"traceutil/trace.go:171","msg":"trace[1612709381] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:9316; }","duration":"155.651367ms","start":"2026-05-22T19:21:05.349682Z","end":"2026-05-22T19:21:05.505333Z","steps":["trace[1612709381] 'agreement among raft nodes before linearized reading' (duration: 154.78364ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.667737Z","caller":"traceutil/trace.go:171","msg":"trace[1300529776] linearizableReadLoop","detail":"{readStateIndex:9643; appliedIndex:9641; }","duration":"145.400828ms","start":"2026-05-22T19:21:05.522302Z","end":"2026-05-22T19:21:05.667703Z","steps":["trace[1300529776] 'read index received' (duration: 145.202994ms)","trace[1300529776] 'applied index is now lower than readState.Index' (duration: 196.303µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:21:05.667806Z","caller":"traceutil/trace.go:171","msg":"trace[428429830] transaction","detail":"{read_only:false; response_revision:9319; number_of_response:1; }","duration":"159.041276ms","start":"2026-05-22T19:21:05.508726Z","end":"2026-05-22T19:21:05.667767Z","steps":["trace[428429830] 'process raft request' (duration: 158.921963ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.667769Z","caller":"traceutil/trace.go:171","msg":"trace[176697338] transaction","detail":"{read_only:false; response_revision:9318; number_of_response:1; }","duration":"159.314101ms","start":"2026-05-22T19:21:05.50842Z","end":"2026-05-22T19:21:05.667734Z","steps":["trace[176697338] 'process raft request' (duration: 159.032496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:21:05.668059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.391363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-22T19:21:05.668171Z","caller":"traceutil/trace.go:171","msg":"trace[264469016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9319; }","duration":"157.490515ms","start":"2026-05-22T19:21:05.510603Z","end":"2026-05-22T19:21:05.668094Z","steps":["trace[264469016] 'agreement among raft nodes before linearized reading' (duration: 157.2509ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:05.679012Z","caller":"traceutil/trace.go:171","msg":"trace[1472214923] transaction","detail":"{read_only:false; response_revision:9320; number_of_response:1; }","duration":"152.853819ms","start":"2026-05-22T19:21:05.526135Z","end":"2026-05-22T19:21:05.678989Z","steps":["trace[1472214923] 'process raft request' (duration: 152.733417ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:21:54.338731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-22T19:21:54.34467Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-22T19:21:54.344738Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-22T19:24:11.027858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8279} {"level":"info","ts":"2026-05-22T19:24:11.109319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8279,"took":"79.246675ms","hash":2732892752,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":25452544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-22T19:24:11.109396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2732892752,"revision":8279,"compact-revision":6100} {"level":"info","ts":"2026-05-22T19:24:49.411032Z","caller":"traceutil/trace.go:171","msg":"trace[445422641] linearizableReadLoop","detail":"{readStateIndex:11547; appliedIndex:11546; }","duration":"161.517631ms","start":"2026-05-22T19:24:49.249475Z","end":"2026-05-22T19:24:49.410993Z","steps":["trace[445422641] 'read index received' (duration: 98.266632ms)","trace[445422641] 'applied index is now lower than readState.Index' (duration: 63.246709ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:24:49.411153Z","caller":"traceutil/trace.go:171","msg":"trace[1141291470] transaction","detail":"{read_only:false; response_revision:11168; number_of_response:1; }","duration":"132.479699ms","start":"2026-05-22T19:24:49.27862Z","end":"2026-05-22T19:24:49.411099Z","steps":["trace[1141291470] 'process raft request' (duration: 132.250874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:24:49.411315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.864284ms","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-05-22T19:24:49.411357Z","caller":"traceutil/trace.go:171","msg":"trace[497937905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11168; }","duration":"125.945326ms","start":"2026-05-22T19:24:49.285399Z","end":"2026-05-22T19:24:49.411344Z","steps":["trace[497937905] 'agreement among raft nodes before linearized reading' (duration: 125.764002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:24:49.411314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.800946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xtwkv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-22T19:24:49.411439Z","caller":"traceutil/trace.go:171","msg":"trace[1246521428] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xtwkv; range_end:; response_count:1; response_revision:11168; }","duration":"161.99494ms","start":"2026-05-22T19:24:49.249413Z","end":"2026-05-22T19:24:49.411408Z","steps":["trace[1246521428] 'agreement among raft nodes before linearized reading' (duration: 161.657533ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:29:11.035419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10769} {"level":"info","ts":"2026-05-22T19:29:11.114172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10769,"took":"74.380501ms","hash":3328406841,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-22T19:29:11.114232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328406841,"revision":10769,"compact-revision":8279} {"level":"info","ts":"2026-05-22T19:31:44.023818Z","caller":"traceutil/trace.go:171","msg":"trace[1119292823] transaction","detail":"{read_only:false; response_revision:14104; number_of_response:1; }","duration":"211.965263ms","start":"2026-05-22T19:31:43.811827Z","end":"2026-05-22T19:31:44.023792Z","steps":["trace[1119292823] 'process raft request' (duration: 211.80358ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:31:44.03078Z","caller":"traceutil/trace.go:171","msg":"trace[1653065541] transaction","detail":"{read_only:false; response_revision:14105; number_of_response:1; }","duration":"136.862691ms","start":"2026-05-22T19:31:43.89389Z","end":"2026-05-22T19:31:44.030753Z","steps":["trace[1653065541] 'process raft request' (duration: 136.599605ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:31:44.031758Z","caller":"traceutil/trace.go:171","msg":"trace[858787806] linearizableReadLoop","detail":"{readStateIndex:14592; appliedIndex:14589; }","duration":"124.920322ms","start":"2026-05-22T19:31:43.906821Z","end":"2026-05-22T19:31:44.031741Z","steps":["trace[858787806] 'read index received' (duration: 116.875603ms)","trace[858787806] 'applied index is now lower than readState.Index' (duration: 8.042449ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:31:44.031794Z","caller":"traceutil/trace.go:171","msg":"trace[1240522813] transaction","detail":"{read_only:false; response_revision:14106; number_of_response:1; }","duration":"136.110134ms","start":"2026-05-22T19:31:43.895664Z","end":"2026-05-22T19:31:44.031774Z","steps":["trace[1240522813] 'process raft request' (duration: 134.986523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:31:44.031956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.118878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-22T19:31:44.031991Z","caller":"traceutil/trace.go:171","msg":"trace[970513031] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14106; }","duration":"125.2217ms","start":"2026-05-22T19:31:43.90676Z","end":"2026-05-22T19:31:44.031982Z","steps":["trace[970513031] 'agreement among raft nodes before linearized reading' (duration: 125.060495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T19:31:44.032072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.122999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec438891-5a02-46cd-b3ea-4de05600d27d\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-22T19:31:44.032129Z","caller":"traceutil/trace.go:171","msg":"trace[744388074] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec438891-5a02-46cd-b3ea-4de05600d27d; range_end:; response_count:1; response_revision:14106; }","duration":"110.248002ms","start":"2026-05-22T19:31:43.921866Z","end":"2026-05-22T19:31:44.032114Z","steps":["trace[744388074] 'agreement among raft nodes before linearized reading' (duration: 110.088979ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:32:58.820558Z","caller":"traceutil/trace.go:171","msg":"trace[446260973] transaction","detail":"{read_only:false; response_revision:14857; number_of_response:1; }","duration":"108.341328ms","start":"2026-05-22T19:32:58.712183Z","end":"2026-05-22T19:32:58.820524Z","steps":["trace[446260973] 'process raft request' (duration: 95.306618ms)","trace[446260973] 'compare' (duration: 12.820686ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T19:34:11.045128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12945} {"level":"info","ts":"2026-05-22T19:34:11.120136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12945,"took":"72.431983ms","hash":250121265,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-22T19:34:11.120208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":250121265,"revision":12945,"compact-revision":10769} {"level":"info","ts":"2026-05-22T19:36:02.635939Z","caller":"traceutil/trace.go:171","msg":"trace[1875192232] transaction","detail":"{read_only:false; response_revision:16332; number_of_response:1; }","duration":"118.343927ms","start":"2026-05-22T19:36:02.517569Z","end":"2026-05-22T19:36:02.635913Z","steps":["trace[1875192232] 'process raft request' (duration: 118.135063ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T19:39:11.05173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15312} {"level":"info","ts":"2026-05-22T19:39:11.152074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15312,"took":"94.571656ms","hash":652389143,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":31174656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-22T19:39:11.152159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":652389143,"revision":15312,"compact-revision":12945} {"level":"info","ts":"2026-05-22T19:44:11.058188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17537} {"level":"info","ts":"2026-05-22T19:44:11.149754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17537,"took":"88.516993ms","hash":4114682090,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":26112000,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-22T19:44:11.149827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4114682090,"revision":17537,"compact-revision":15312} {"level":"info","ts":"2026-05-22T19:44:53.340967Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-22T19:44:53.345307Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-22T19:44:53.3454Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}