{"level":"warn","ts":"2026-06-15T11:43:04.128855Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T11:43:04.128955Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-15T11:43:04.129024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T11:43:04.129038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-15T11:43:04.129057Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T11:43:04.129702Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-06-15T11:43:04.1298Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-15T11:43:04.135718Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.223598ms"} {"level":"info","ts":"2026-06-15T11:43:04.147366Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-06-15T11:43:04.147524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T11:43:04.147633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-06-15T11:43:04.147738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T11:43:04.147819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-06-15T11:43:04.147953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-06-15T11:43:04.156727Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T11:43:04.158358Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T11:43:04.168996Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-15T11:43:04.171359Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T11:43:04.172084Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T11:43:04.172288Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T11:43:04.172311Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T11:43:04.172732Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T11:43:04.173861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-06-15T11:43:04.174011Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-15T11:43:04.175905Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T11:43:04.175938Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-15T11:43:04.17598Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-15T11:43:04.176474Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T11:43:04.176512Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T11:43:04.548683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T11:43:04.548766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T11:43:04.548806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-06-15T11:43:04.548823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-06-15T11:43:04.548833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-15T11:43:04.548845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-06-15T11:43:04.549009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-15T11:43:04.550287Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T11:43:04.550311Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T11:43:04.55035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T11:43:04.550647Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:43:04.55094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T11:43:04.551048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T11:43:04.554147Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:43:04.55433Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:43:04.554372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:43:04.557316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T11:43:04.557813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"warn","ts":"2026-06-15T11:43:28.72122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.478524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T11:43:28.721316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.706702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T11:43:28.721374Z","caller":"traceutil/trace.go:171","msg":"trace[317318118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"114.72264ms","start":"2026-06-15T11:43:28.606628Z","end":"2026-06-15T11:43:28.721351Z","steps":["trace[317318118] 'range keys from in-memory index tree' (duration: 114.323362ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:43:28.721434Z","caller":"traceutil/trace.go:171","msg":"trace[1894093273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"269.921628ms","start":"2026-06-15T11:43:28.451486Z","end":"2026-06-15T11:43:28.721408Z","steps":["trace[1894093273] 'range keys from in-memory index tree' (duration: 269.588899ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:43:47.905289Z","caller":"traceutil/trace.go:171","msg":"trace[1505375766] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"134.972765ms","start":"2026-06-15T11:43:47.770282Z","end":"2026-06-15T11:43:47.905255Z","steps":["trace[1505375766] 'read index received' (duration: 134.837022ms)","trace[1505375766] 'applied index is now lower than readState.Index' (duration: 134.243µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:43:47.905455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.156338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T11:43:47.905503Z","caller":"traceutil/trace.go:171","msg":"trace[1851303131] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:612; }","duration":"135.26785ms","start":"2026-06-15T11:43:47.770219Z","end":"2026-06-15T11:43:47.905487Z","steps":["trace[1851303131] 'agreement among raft nodes before linearized reading' (duration: 135.159068ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:43:47.905601Z","caller":"traceutil/trace.go:171","msg":"trace[1887391186] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"162.275712ms","start":"2026-06-15T11:43:47.743206Z","end":"2026-06-15T11:43:47.905481Z","steps":["trace[1887391186] 'process raft request' (duration: 161.810653ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:43:48.030455Z","caller":"traceutil/trace.go:171","msg":"trace[771913904] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"254.354576ms","start":"2026-06-15T11:43:47.776054Z","end":"2026-06-15T11:43:48.030408Z","steps":["trace[771913904] 'process raft request' (duration: 218.06523ms)","trace[771913904] 'compare' (duration: 35.662144ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:43:48.509606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.890104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:43:48.509671Z","caller":"traceutil/trace.go:171","msg":"trace[1473262747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"152.966855ms","start":"2026-06-15T11:43:48.35669Z","end":"2026-06-15T11:43:48.509656Z","steps":["trace[1473262747] 'range keys from in-memory index tree' (duration: 152.763291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:48.509692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.312197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-15T11:43:48.509771Z","caller":"traceutil/trace.go:171","msg":"trace[683644132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"268.430189ms","start":"2026-06-15T11:43:48.241315Z","end":"2026-06-15T11:43:48.509745Z","steps":["trace[683644132] 'range keys from in-memory index tree' (duration: 268.082183ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:43:48.875949Z","caller":"traceutil/trace.go:171","msg":"trace[1052921056] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"362.063495ms","start":"2026-06-15T11:43:48.513847Z","end":"2026-06-15T11:43:48.87591Z","steps":["trace[1052921056] 'process raft request' (duration: 361.77718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:48.876893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:43:48.513813Z","time spent":"362.267948ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:43:49.15886Z","caller":"traceutil/trace.go:171","msg":"trace[16433089] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"644.607307ms","start":"2026-06-15T11:43:48.514218Z","end":"2026-06-15T11:43:49.158826Z","steps":["trace[16433089] 'process raft request' (duration: 641.164688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:49.159047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:43:48.514191Z","time spent":"644.75138ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:43:49.163952Z","caller":"traceutil/trace.go:171","msg":"trace[1244036648] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"327.043984ms","start":"2026-06-15T11:43:48.836779Z","end":"2026-06-15T11:43:49.163823Z","steps":["trace[1244036648] 'read index received' (duration: 39.041042ms)","trace[1244036648] 'applied index is now lower than readState.Index' (duration: 288.000032ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:43:49.164245Z","caller":"traceutil/trace.go:171","msg":"trace[940910420] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"637.326581ms","start":"2026-06-15T11:43:48.526811Z","end":"2026-06-15T11:43:49.164137Z","steps":["trace[940910420] 'process raft request' (duration: 632.411743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:49.164523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:43:48.526789Z","time spent":"637.600026ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:43:49.164536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.167945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T11:43:49.16469Z","caller":"traceutil/trace.go:171","msg":"trace[1955360441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"327.35913ms","start":"2026-06-15T11:43:48.837286Z","end":"2026-06-15T11:43:49.164646Z","steps":["trace[1955360441] 'agreement among raft nodes before linearized reading' (duration: 326.923471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:49.164767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:43:48.837218Z","time spent":"327.534993ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T11:43:49.164688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.767688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T11:43:49.164926Z","caller":"traceutil/trace.go:171","msg":"trace[242823595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"328.156596ms","start":"2026-06-15T11:43:48.836716Z","end":"2026-06-15T11:43:49.164872Z","steps":["trace[242823595] 'agreement among raft nodes before linearized reading' (duration: 327.38731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:43:49.16518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:43:48.836689Z","time spent":"328.370501ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T11:44:31.27757Z","caller":"traceutil/trace.go:171","msg":"trace[854755174] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"160.926525ms","start":"2026-06-15T11:44:31.116607Z","end":"2026-06-15T11:44:31.277534Z","steps":["trace[854755174] 'process raft request' (duration: 160.710821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:21.628151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.584252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:45:21.628323Z","caller":"traceutil/trace.go:171","msg":"trace[846053243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"270.821997ms","start":"2026-06-15T11:45:21.357478Z","end":"2026-06-15T11:45:21.6283Z","steps":["trace[846053243] 'range keys from in-memory index tree' (duration: 270.398698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:21.628346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.6541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81455480-08d2-4f35-9411-d7d23b32fab1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-15T11:45:21.628463Z","caller":"traceutil/trace.go:171","msg":"trace[464818456] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-81455480-08d2-4f35-9411-d7d23b32fab1; range_end:; response_count:1; response_revision:1873; }","duration":"362.861134ms","start":"2026-06-15T11:45:21.265577Z","end":"2026-06-15T11:45:21.628438Z","steps":["trace[464818456] 'range keys from in-memory index tree' (duration: 362.398535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:21.628516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:45:21.265542Z","time spent":"362.964516ms","remote":"127.0.0.1:44590","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-81455480-08d2-4f35-9411-d7d23b32fab1\" "} {"level":"info","ts":"2026-06-15T11:45:21.637555Z","caller":"traceutil/trace.go:171","msg":"trace[101081353] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"165.301356ms","start":"2026-06-15T11:45:21.472212Z","end":"2026-06-15T11:45:21.637514Z","steps":["trace[101081353] 'process raft request' (duration: 165.158064ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:22.236641Z","caller":"traceutil/trace.go:171","msg":"trace[86033067] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"337.581914ms","start":"2026-06-15T11:45:21.899027Z","end":"2026-06-15T11:45:22.236609Z","steps":["trace[86033067] 'process raft request' (duration: 317.990559ms)","trace[86033067] 'compare' (duration: 19.382021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:45:22.236832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:45:21.898998Z","time spent":"337.738417ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:45:22.23693Z","caller":"traceutil/trace.go:171","msg":"trace[1079185624] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1955; }","duration":"134.675748ms","start":"2026-06-15T11:45:22.102201Z","end":"2026-06-15T11:45:22.236877Z","steps":["trace[1079185624] 'read index received' (duration: 114.893579ms)","trace[1079185624] 'applied index is now lower than readState.Index' (duration: 19.780119ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:45:22.237146Z","caller":"traceutil/trace.go:171","msg":"trace[1472646333] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"335.44911ms","start":"2026-06-15T11:45:21.901683Z","end":"2026-06-15T11:45:22.237132Z","steps":["trace[1472646333] 'process raft request' (duration: 334.878959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:22.237348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:45:21.901669Z","time spent":"335.515672ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:45:22.237507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.268311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T11:45:22.237618Z","caller":"traceutil/trace.go:171","msg":"trace[1617793050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"135.460195ms","start":"2026-06-15T11:45:22.10213Z","end":"2026-06-15T11:45:22.23759Z","steps":["trace[1617793050] 'agreement among raft nodes before linearized reading' (duration: 135.084047ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:33.879097Z","caller":"traceutil/trace.go:171","msg":"trace[153521401] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2053; }","duration":"157.391857ms","start":"2026-06-15T11:45:33.721677Z","end":"2026-06-15T11:45:33.879069Z","steps":["trace[153521401] 'read index received' (duration: 157.106521ms)","trace[153521401] 'applied index is now lower than readState.Index' (duration: 284.086µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:45:33.879432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.744754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T11:45:33.879532Z","caller":"traceutil/trace.go:171","msg":"trace[1536402741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1974; }","duration":"157.856276ms","start":"2026-06-15T11:45:33.721655Z","end":"2026-06-15T11:45:33.879511Z","steps":["trace[1536402741] 'agreement among raft nodes before linearized reading' (duration: 157.601571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:33.87954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.467211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T11:45:33.879556Z","caller":"traceutil/trace.go:171","msg":"trace[2144479966] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"178.445662ms","start":"2026-06-15T11:45:33.701066Z","end":"2026-06-15T11:45:33.879512Z","steps":["trace[2144479966] 'process raft request' (duration: 177.810629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:33.879603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.130344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T11:45:33.879644Z","caller":"traceutil/trace.go:171","msg":"trace[1996801559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1974; }","duration":"117.640445ms","start":"2026-06-15T11:45:33.761981Z","end":"2026-06-15T11:45:33.879621Z","steps":["trace[1996801559] 'agreement among raft nodes before linearized reading' (duration: 117.374119ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:33.879654Z","caller":"traceutil/trace.go:171","msg":"trace[83915825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1974; }","duration":"114.217396ms","start":"2026-06-15T11:45:33.765424Z","end":"2026-06-15T11:45:33.879642Z","steps":["trace[83915825] 'agreement among raft nodes before linearized reading' (duration: 114.025992ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:34.491019Z","caller":"traceutil/trace.go:171","msg":"trace[1745547122] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2059; }","duration":"164.120593ms","start":"2026-06-15T11:45:34.326866Z","end":"2026-06-15T11:45:34.490987Z","steps":["trace[1745547122] 'read index received' (duration: 130.260749ms)","trace[1745547122] 'applied index is now lower than readState.Index' (duration: 33.858524ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:45:34.49111Z","caller":"traceutil/trace.go:171","msg":"trace[1694286803] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"286.078914ms","start":"2026-06-15T11:45:34.204984Z","end":"2026-06-15T11:45:34.491063Z","steps":["trace[1694286803] 'process raft request' (duration: 285.86308ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:34.491441Z","caller":"traceutil/trace.go:171","msg":"trace[1949003682] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"289.532494ms","start":"2026-06-15T11:45:34.201895Z","end":"2026-06-15T11:45:34.491428Z","steps":["trace[1949003682] 'process raft request' (duration: 255.210681ms)","trace[1949003682] 'compare' (duration: 33.534727ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:45:34.491763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.534915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:45:34.49182Z","caller":"traceutil/trace.go:171","msg":"trace[141413046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1983; }","duration":"134.664958ms","start":"2026-06-15T11:45:34.357141Z","end":"2026-06-15T11:45:34.491806Z","steps":["trace[141413046] 'agreement among raft nodes before linearized reading' (duration: 134.547175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:45:34.491851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.96825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T11:45:34.491865Z","caller":"traceutil/trace.go:171","msg":"trace[625752128] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"200.852714ms","start":"2026-06-15T11:45:34.290985Z","end":"2026-06-15T11:45:34.491838Z","steps":["trace[625752128] 'process raft request' (duration: 199.922135ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:34.491946Z","caller":"traceutil/trace.go:171","msg":"trace[1473405333] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1983; }","duration":"165.114573ms","start":"2026-06-15T11:45:34.326809Z","end":"2026-06-15T11:45:34.491923Z","steps":["trace[1473405333] 'agreement among raft nodes before linearized reading' (duration: 164.847178ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:45:34.492001Z","caller":"traceutil/trace.go:171","msg":"trace[170241857] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"200.471657ms","start":"2026-06-15T11:45:34.291499Z","end":"2026-06-15T11:45:34.491971Z","steps":["trace[170241857] 'process raft request' (duration: 199.452786ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:46:07.219533Z","caller":"traceutil/trace.go:171","msg":"trace[928120278] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"104.854203ms","start":"2026-06-15T11:46:07.114631Z","end":"2026-06-15T11:46:07.219486Z","steps":["trace[928120278] 'process raft request' (duration: 96.519116ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:46:07.651831Z","caller":"traceutil/trace.go:171","msg":"trace[1692138415] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"171.649362ms","start":"2026-06-15T11:46:07.480143Z","end":"2026-06-15T11:46:07.651792Z","steps":["trace[1692138415] 'process raft request' (duration: 171.428318ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:46:13.317008Z","caller":"traceutil/trace.go:171","msg":"trace[1042983342] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"238.370431ms","start":"2026-06-15T11:46:13.078606Z","end":"2026-06-15T11:46:13.316976Z","steps":["trace[1042983342] 'process raft request' (duration: 238.179147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:46:13.840215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.378967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:46:13.840403Z","caller":"traceutil/trace.go:171","msg":"trace[552904755] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"731.483629ms","start":"2026-06-15T11:46:13.108879Z","end":"2026-06-15T11:46:13.840362Z","steps":["trace[552904755] 'process raft request' (duration: 362.655583ms)","trace[552904755] 'compare' (duration: 368.038371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:46:13.84061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:46:13.108865Z","time spent":"731.628633ms","remote":"127.0.0.1:44654","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-06-15T11:46:13.840692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.188077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-15T11:46:13.840787Z","caller":"traceutil/trace.go:171","msg":"trace[2121846894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2205; }","duration":"596.34558ms","start":"2026-06-15T11:46:13.244422Z","end":"2026-06-15T11:46:13.840767Z","steps":["trace[2121846894] 'agreement among raft nodes before linearized reading' (duration: 596.060243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:46:13.840868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:46:13.244394Z","time spent":"596.465162ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T11:46:13.840356Z","caller":"traceutil/trace.go:171","msg":"trace[817769432] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2291; }","duration":"595.844738ms","start":"2026-06-15T11:46:13.244486Z","end":"2026-06-15T11:46:13.840331Z","steps":["trace[817769432] 'read index received' (duration: 72.36294ms)","trace[817769432] 'applied index is now lower than readState.Index' (duration: 523.480408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:46:13.841159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.811895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b93e979211b59b\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-15T11:46:13.841201Z","caller":"traceutil/trace.go:171","msg":"trace[405966118] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b93e979211b59b; range_end:; response_count:1; response_revision:2205; }","duration":"518.884147ms","start":"2026-06-15T11:46:13.322297Z","end":"2026-06-15T11:46:13.841181Z","steps":["trace[405966118] 'agreement among raft nodes before linearized reading' (duration: 518.771685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:46:13.841279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:46:13.322277Z","time spent":"518.951438ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b93e979211b59b\" "} {"level":"warn","ts":"2026-06-15T11:46:13.841359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.546287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T11:46:13.841338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.427076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-06-15T11:46:13.841433Z","caller":"traceutil/trace.go:171","msg":"trace[184229658] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2205; }","duration":"132.588029ms","start":"2026-06-15T11:46:13.708822Z","end":"2026-06-15T11:46:13.84141Z","steps":["trace[184229658] 'agreement among raft nodes before linearized reading' (duration: 132.268243ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:46:13.841519Z","caller":"traceutil/trace.go:171","msg":"trace[1299529795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2205; }","duration":"483.642209ms","start":"2026-06-15T11:46:13.357753Z","end":"2026-06-15T11:46:13.841395Z","steps":["trace[1299529795] 'agreement among raft nodes before linearized reading' (duration: 483.560097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:46:13.841542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:46:13.35773Z","time spent":"483.807163ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T11:46:14.078022Z","caller":"traceutil/trace.go:171","msg":"trace[20751201] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2293; }","duration":"226.169505ms","start":"2026-06-15T11:46:13.851825Z","end":"2026-06-15T11:46:14.077995Z","steps":["trace[20751201] 'read index received' (duration: 224.698476ms)","trace[20751201] 'applied index is now lower than readState.Index' (duration: 1.470169ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:46:14.078211Z","caller":"traceutil/trace.go:171","msg":"trace[2105678045] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"231.784788ms","start":"2026-06-15T11:46:13.846397Z","end":"2026-06-15T11:46:14.078182Z","steps":["trace[2105678045] 'process raft request' (duration: 231.36309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:46:14.078311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.465651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:46:14.078369Z","caller":"traceutil/trace.go:171","msg":"trace[1540564283] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2207; }","duration":"226.545672ms","start":"2026-06-15T11:46:13.851807Z","end":"2026-06-15T11:46:14.078353Z","steps":["trace[1540564283] 'agreement among raft nodes before linearized reading' (duration: 226.298047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:47:36.532681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.293029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:47:36.532794Z","caller":"traceutil/trace.go:171","msg":"trace[28040382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2844; }","duration":"175.470512ms","start":"2026-06-15T11:47:36.357302Z","end":"2026-06-15T11:47:36.532772Z","steps":["trace[28040382] 'range keys from in-memory index tree' (duration: 175.180786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:47:36.532786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.431555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:47:36.532876Z","caller":"traceutil/trace.go:171","msg":"trace[2072790563] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2844; }","duration":"120.593438ms","start":"2026-06-15T11:47:36.412262Z","end":"2026-06-15T11:47:36.532856Z","steps":["trace[2072790563] 'count revisions from in-memory index tree' (duration: 120.232271ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:47:36.5951Z","caller":"traceutil/trace.go:171","msg":"trace[2061399952] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"164.488285ms","start":"2026-06-15T11:47:36.43056Z","end":"2026-06-15T11:47:36.595048Z","steps":["trace[2061399952] 'process raft request' (duration: 163.950665ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:47:37.146986Z","caller":"traceutil/trace.go:171","msg":"trace[373466779] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"136.489978ms","start":"2026-06-15T11:47:37.010459Z","end":"2026-06-15T11:47:37.146948Z","steps":["trace[373466779] 'process raft request' (duration: 136.283534ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:47:37.198121Z","caller":"traceutil/trace.go:171","msg":"trace[1142828243] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"187.265261ms","start":"2026-06-15T11:47:37.01081Z","end":"2026-06-15T11:47:37.198076Z","steps":["trace[1142828243] 'process raft request' (duration: 148.050735ms)","trace[1142828243] 'compare' (duration: 38.88629ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:47:42.608893Z","caller":"traceutil/trace.go:171","msg":"trace[1592324488] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"124.76264ms","start":"2026-06-15T11:47:42.484101Z","end":"2026-06-15T11:47:42.608864Z","steps":["trace[1592324488] 'process raft request' (duration: 124.597727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:47:48.594284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.962013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18b93eaee403d56e\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-06-15T11:47:48.594479Z","caller":"traceutil/trace.go:171","msg":"trace[1912938646] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18b93eaee403d56e; range_end:; response_count:1; response_revision:2911; }","duration":"126.30376ms","start":"2026-06-15T11:47:48.468134Z","end":"2026-06-15T11:47:48.594438Z","steps":["trace[1912938646] 'range keys from in-memory index tree' (duration: 125.750909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:50:17.886725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.206225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-15T11:50:17.886862Z","caller":"traceutil/trace.go:171","msg":"trace[952073904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3892; }","duration":"235.375289ms","start":"2026-06-15T11:50:17.651464Z","end":"2026-06-15T11:50:17.886839Z","steps":["trace[952073904] 'range keys from in-memory index tree' (duration: 235.012241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:50:17.886739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.272145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-15T11:50:17.886952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.131481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T11:50:17.887015Z","caller":"traceutil/trace.go:171","msg":"trace[102957910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3892; }","duration":"237.610312ms","start":"2026-06-15T11:50:17.649351Z","end":"2026-06-15T11:50:17.886962Z","steps":["trace[102957910] 'range keys from in-memory index tree' (duration: 237.001339ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:50:17.887102Z","caller":"traceutil/trace.go:171","msg":"trace[1763816388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3892; }","duration":"255.335685ms","start":"2026-06-15T11:50:17.631731Z","end":"2026-06-15T11:50:17.887067Z","steps":["trace[1763816388] 'range keys from in-memory index tree' (duration: 254.900267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:50:17.887091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.839781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-06-15T11:50:17.887009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.481852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-15T11:50:17.887212Z","caller":"traceutil/trace.go:171","msg":"trace[1607241513] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3892; }","duration":"246.015864ms","start":"2026-06-15T11:50:17.641169Z","end":"2026-06-15T11:50:17.887185Z","steps":["trace[1607241513] 'range keys from in-memory index tree' (duration: 245.161167ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:50:17.887289Z","caller":"traceutil/trace.go:171","msg":"trace[795120739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3892; }","duration":"234.815698ms","start":"2026-06-15T11:50:17.652458Z","end":"2026-06-15T11:50:17.887274Z","steps":["trace[795120739] 'range keys from in-memory index tree' (duration: 234.300008ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:37.120704Z","caller":"traceutil/trace.go:171","msg":"trace[1016249343] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"101.705228ms","start":"2026-06-15T11:51:37.018945Z","end":"2026-06-15T11:51:37.120651Z","steps":["trace[1016249343] 'process raft request' (duration: 101.217277ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:44.866822Z","caller":"traceutil/trace.go:171","msg":"trace[1156054253] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"110.610442ms","start":"2026-06-15T11:51:44.756178Z","end":"2026-06-15T11:51:44.866788Z","steps":["trace[1156054253] 'process raft request' (duration: 110.146884ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:01.8575Z","caller":"traceutil/trace.go:171","msg":"trace[1269563257] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"193.612811ms","start":"2026-06-15T11:52:01.663863Z","end":"2026-06-15T11:52:01.857476Z","steps":["trace[1269563257] 'process raft request' (duration: 193.406207ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:01.857773Z","caller":"traceutil/trace.go:171","msg":"trace[382713832] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"193.724274ms","start":"2026-06-15T11:52:01.664033Z","end":"2026-06-15T11:52:01.857757Z","steps":["trace[382713832] 'process raft request' (duration: 193.373417ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:01.857886Z","caller":"traceutil/trace.go:171","msg":"trace[1749223413] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"193.56476ms","start":"2026-06-15T11:52:01.66429Z","end":"2026-06-15T11:52:01.857854Z","steps":["trace[1749223413] 'process raft request' (duration: 193.343526ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:01.857963Z","caller":"traceutil/trace.go:171","msg":"trace[1323388683] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5583; }","duration":"135.226826ms","start":"2026-06-15T11:52:01.722709Z","end":"2026-06-15T11:52:01.857936Z","steps":["trace[1323388683] 'read index received' (duration: 8.607809ms)","trace[1323388683] 'applied index is now lower than readState.Index' (duration: 126.617527ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:52:01.858106Z","caller":"traceutil/trace.go:171","msg":"trace[1178250746] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"174.022107ms","start":"2026-06-15T11:52:01.68404Z","end":"2026-06-15T11:52:01.858062Z","steps":["trace[1178250746] 'process raft request' (duration: 173.736631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:01.858164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.43888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T11:52:01.860119Z","caller":"traceutil/trace.go:171","msg":"trace[2026476303] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5382; }","duration":"137.449299ms","start":"2026-06-15T11:52:01.722649Z","end":"2026-06-15T11:52:01.860099Z","steps":["trace[2026476303] 'agreement among raft nodes before linearized reading' (duration: 135.43777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:09.621222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.233699ms","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":"warn","ts":"2026-06-15T11:52:09.621314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.534949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-16eea090-a850-4348-b726-0d70d0c151f2\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-15T11:52:09.621358Z","caller":"traceutil/trace.go:171","msg":"trace[2044624199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5541; }","duration":"292.442363ms","start":"2026-06-15T11:52:09.328894Z","end":"2026-06-15T11:52:09.621336Z","steps":["trace[2044624199] 'count revisions from in-memory index tree' (duration: 292.151886ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:09.621372Z","caller":"traceutil/trace.go:171","msg":"trace[92742730] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-16eea090-a850-4348-b726-0d70d0c151f2; range_end:; response_count:1; response_revision:5541; }","duration":"195.62644ms","start":"2026-06-15T11:52:09.425728Z","end":"2026-06-15T11:52:09.621355Z","steps":["trace[92742730] 'range keys from in-memory index tree' (duration: 195.171492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:09.621442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.983135ms","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":"info","ts":"2026-06-15T11:52:09.621587Z","caller":"traceutil/trace.go:171","msg":"trace[802163273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5541; }","duration":"342.23681ms","start":"2026-06-15T11:52:09.279318Z","end":"2026-06-15T11:52:09.621555Z","steps":["trace[802163273] 'count revisions from in-memory index tree' (duration: 341.816272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:09.621622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.904261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T11:52:09.621681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:52:09.279281Z","time spent":"342.373183ms","remote":"127.0.0.1:56810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T11:52:09.621724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.93764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-15T11:52:09.621706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.616277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxdm6\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-15T11:52:09.621816Z","caller":"traceutil/trace.go:171","msg":"trace[2012960319] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"155.434083ms","start":"2026-06-15T11:52:09.466365Z","end":"2026-06-15T11:52:09.621799Z","steps":["trace[2012960319] 'process raft request' (duration: 51.324028ms)","trace[2012960319] 'compare' (duration: 103.562383ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:52:09.621827Z","caller":"traceutil/trace.go:171","msg":"trace[786723860] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxdm6; range_end:; response_count:1; response_revision:5541; }","duration":"317.7723ms","start":"2026-06-15T11:52:09.304028Z","end":"2026-06-15T11:52:09.6218Z","steps":["trace[786723860] 'range keys from in-memory index tree' (duration: 317.378021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:09.621873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:52:09.304016Z","time spent":"317.847001ms","remote":"127.0.0.1:44590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-jxdm6\" "} {"level":"info","ts":"2026-06-15T11:52:09.621746Z","caller":"traceutil/trace.go:171","msg":"trace[748536892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5541; }","duration":"265.098886ms","start":"2026-06-15T11:52:09.35662Z","end":"2026-06-15T11:52:09.621719Z","steps":["trace[748536892] 'range keys from in-memory index tree' (duration: 264.758839ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:09.863146Z","caller":"traceutil/trace.go:171","msg":"trace[1343125327] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"216.579593ms","start":"2026-06-15T11:52:09.64654Z","end":"2026-06-15T11:52:09.863119Z","steps":["trace[1343125327] 'process raft request' (duration: 216.392019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:10.20058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.240168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-15T11:52:10.200664Z","caller":"traceutil/trace.go:171","msg":"trace[218250705] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5544; }","duration":"237.38203ms","start":"2026-06-15T11:52:09.963264Z","end":"2026-06-15T11:52:10.200646Z","steps":["trace[218250705] 'range keys from in-memory index tree' (duration: 237.140206ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:53:04.592058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-06-15T11:53:04.670404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"76.306092ms","hash":3649774086,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":33718272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-15T11:53:04.670471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3649774086,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-06-15T11:58:04.600073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6070} {"level":"info","ts":"2026-06-15T11:58:04.708744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6070,"took":"105.827822ms","hash":115980810,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":21671936,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-15T11:58:04.708827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":115980810,"revision":6070,"compact-revision":2998} {"level":"info","ts":"2026-06-15T11:58:27.565047Z","caller":"traceutil/trace.go:171","msg":"trace[666111710] linearizableReadLoop","detail":"{readStateIndex:8740; appliedIndex:8739; }","duration":"207.047013ms","start":"2026-06-15T11:58:27.357961Z","end":"2026-06-15T11:58:27.565008Z","steps":["trace[666111710] 'read index received' (duration: 206.784588ms)","trace[666111710] 'applied index is now lower than readState.Index' (duration: 260.515µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:58:27.565523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.529083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:27.565588Z","caller":"traceutil/trace.go:171","msg":"trace[1997023412] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"288.624412ms","start":"2026-06-15T11:58:27.276928Z","end":"2026-06-15T11:58:27.565552Z","steps":["trace[1997023412] 'process raft request' (duration: 287.849387ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:27.565614Z","caller":"traceutil/trace.go:171","msg":"trace[674531864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8435; }","duration":"207.680695ms","start":"2026-06-15T11:58:27.357912Z","end":"2026-06-15T11:58:27.565593Z","steps":["trace[674531864] 'agreement among raft nodes before linearized reading' (duration: 207.510582ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:27.608498Z","caller":"traceutil/trace.go:171","msg":"trace[895551321] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"224.776097ms","start":"2026-06-15T11:58:27.383701Z","end":"2026-06-15T11:58:27.608477Z","steps":["trace[895551321] 'process raft request' (duration: 224.599094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:27.60872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.722664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:27.608845Z","caller":"traceutil/trace.go:171","msg":"trace[886496953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8436; }","duration":"189.890029ms","start":"2026-06-15T11:58:27.418935Z","end":"2026-06-15T11:58:27.608825Z","steps":["trace[886496953] 'agreement among raft nodes before linearized reading' (duration: 189.544661ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:38.932175Z","caller":"traceutil/trace.go:171","msg":"trace[127167090] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"137.643251ms","start":"2026-06-15T11:58:38.794504Z","end":"2026-06-15T11:58:38.932147Z","steps":["trace[127167090] 'process raft request' (duration: 137.484098ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:38.938264Z","caller":"traceutil/trace.go:171","msg":"trace[335793220] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"142.72816ms","start":"2026-06-15T11:58:38.795508Z","end":"2026-06-15T11:58:38.938236Z","steps":["trace[335793220] 'process raft request' (duration: 142.561777ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:40.700461Z","caller":"traceutil/trace.go:171","msg":"trace[1466291538] linearizableReadLoop","detail":"{readStateIndex:8811; appliedIndex:8810; }","duration":"179.461924ms","start":"2026-06-15T11:58:40.520975Z","end":"2026-06-15T11:58:40.700437Z","steps":["trace[1466291538] 'read index received' (duration: 179.242649ms)","trace[1466291538] 'applied index is now lower than readState.Index' (duration: 217.735µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:40.700521Z","caller":"traceutil/trace.go:171","msg":"trace[374237519] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"201.206917ms","start":"2026-06-15T11:58:40.499269Z","end":"2026-06-15T11:58:40.700476Z","steps":["trace[374237519] 'process raft request' (duration: 201.002503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:40.700847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.844501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-15T11:58:40.700921Z","caller":"traceutil/trace.go:171","msg":"trace[1640994330] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8503; }","duration":"179.988133ms","start":"2026-06-15T11:58:40.520913Z","end":"2026-06-15T11:58:40.700901Z","steps":["trace[1640994330] 'agreement among raft nodes before linearized reading' (duration: 179.676467ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:09.938543Z","caller":"traceutil/trace.go:171","msg":"trace[2089642917] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"124.073456ms","start":"2026-06-15T11:59:09.814201Z","end":"2026-06-15T11:59:09.938275Z","steps":["trace[2089642917] 'process raft request' (duration: 123.847391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:10.225049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.377453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:59:10.2255Z","caller":"traceutil/trace.go:171","msg":"trace[40841862] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"404.670128ms","start":"2026-06-15T11:59:09.820807Z","end":"2026-06-15T11:59:10.225477Z","steps":["trace[40841862] 'process raft request' (duration: 135.265744ms)","trace[40841862] 'compare' (duration: 267.872374ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:59:10.226187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:59:09.820786Z","time spent":"405.319841ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:59:10.228162Z","caller":"traceutil/trace.go:171","msg":"trace[2014198945] linearizableReadLoop","detail":"{readStateIndex:9035; appliedIndex:9034; }","duration":"223.535712ms","start":"2026-06-15T11:59:10.004604Z","end":"2026-06-15T11:59:10.228139Z","steps":["trace[2014198945] 'read index received' (duration: 223.367218ms)","trace[2014198945] 'applied index is now lower than readState.Index' (duration: 167.534µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:59:10.228375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.774177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:59:10.228456Z","caller":"traceutil/trace.go:171","msg":"trace[416438890] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8719; }","duration":"147.881128ms","start":"2026-06-15T11:59:10.080562Z","end":"2026-06-15T11:59:10.228443Z","steps":["trace[416438890] 'agreement among raft nodes before linearized reading' (duration: 147.769136ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:10.228471Z","caller":"traceutil/trace.go:171","msg":"trace[2041069524] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"269.451185ms","start":"2026-06-15T11:59:09.95872Z","end":"2026-06-15T11:59:10.228171Z","steps":["trace[2041069524] 'process raft request' (duration: 269.279622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:10.228369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.746776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T11:59:10.228726Z","caller":"traceutil/trace.go:171","msg":"trace[1380418002] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8719; }","duration":"224.125643ms","start":"2026-06-15T11:59:10.004547Z","end":"2026-06-15T11:59:10.228673Z","steps":["trace[1380418002] 'agreement among raft nodes before linearized reading' (duration: 223.674314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:10.228764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.536672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-54d44fd497-987lq.18b93f4699c0a970\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-06-15T11:59:10.228821Z","caller":"traceutil/trace.go:171","msg":"trace[487685639] range","detail":"{range_begin:/registry/events/openstack/barbican-api-54d44fd497-987lq.18b93f4699c0a970; range_end:; response_count:1; response_revision:8719; }","duration":"164.637544ms","start":"2026-06-15T11:59:10.064173Z","end":"2026-06-15T11:59:10.228811Z","steps":["trace[487685639] 'agreement among raft nodes before linearized reading' (duration: 164.106514ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:13.267441Z","caller":"traceutil/trace.go:171","msg":"trace[778884551] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"118.128011ms","start":"2026-06-15T11:59:13.149281Z","end":"2026-06-15T11:59:13.267409Z","steps":["trace[778884551] 'process raft request' (duration: 117.843134ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:13.531809Z","caller":"traceutil/trace.go:171","msg":"trace[1247255672] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"259.915519ms","start":"2026-06-15T11:59:13.271813Z","end":"2026-06-15T11:59:13.531728Z","steps":["trace[1247255672] 'process raft request' (duration: 185.679464ms)","trace[1247255672] 'compare' (duration: 73.875828ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:59:13.625677Z","caller":"traceutil/trace.go:171","msg":"trace[438516582] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"268.17514ms","start":"2026-06-15T11:59:13.357482Z","end":"2026-06-15T11:59:13.625657Z","steps":["trace[438516582] 'process raft request' (duration: 268.039057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:22.959073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.160439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T11:59:22.959182Z","caller":"traceutil/trace.go:171","msg":"trace[1750082227] linearizableReadLoop","detail":"{readStateIndex:9150; appliedIndex:9149; }","duration":"121.933033ms","start":"2026-06-15T11:59:22.837226Z","end":"2026-06-15T11:59:22.959159Z","steps":["trace[1750082227] 'read index received' (duration: 1.632921ms)","trace[1750082227] 'applied index is now lower than readState.Index' (duration: 120.298522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:59:22.959287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.049326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:59:22.959343Z","caller":"traceutil/trace.go:171","msg":"trace[292406884] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8827; }","duration":"122.113487ms","start":"2026-06-15T11:59:22.83721Z","end":"2026-06-15T11:59:22.959324Z","steps":["trace[292406884] 'agreement among raft nodes before linearized reading' (duration: 122.010756ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:23.10445Z","caller":"traceutil/trace.go:171","msg":"trace[1217777275] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"132.227603ms","start":"2026-06-15T11:59:22.972188Z","end":"2026-06-15T11:59:23.104416Z","steps":["trace[1217777275] 'process raft request' (duration: 131.976138ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:23.131513Z","caller":"traceutil/trace.go:171","msg":"trace[1503514185] linearizableReadLoop","detail":"{readStateIndex:9152; appliedIndex:9150; }","duration":"155.313624ms","start":"2026-06-15T11:59:22.976171Z","end":"2026-06-15T11:59:23.131484Z","steps":["trace[1503514185] 'read index received' (duration: 128.016742ms)","trace[1503514185] 'applied index is now lower than readState.Index' (duration: 27.295642ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:59:23.131614Z","caller":"traceutil/trace.go:171","msg":"trace[1702975042] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"156.664859ms","start":"2026-06-15T11:59:22.974905Z","end":"2026-06-15T11:59:23.13157Z","steps":["trace[1702975042] 'process raft request' (duration: 156.336283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:23.131777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.574048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T11:59:23.131824Z","caller":"traceutil/trace.go:171","msg":"trace[630744842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8829; }","duration":"155.66368ms","start":"2026-06-15T11:59:22.976147Z","end":"2026-06-15T11:59:23.131811Z","steps":["trace[630744842] 'agreement among raft nodes before linearized reading' (duration: 155.469936ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:23.310495Z","caller":"traceutil/trace.go:171","msg":"trace[992184310] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"174.605269ms","start":"2026-06-15T11:59:23.135855Z","end":"2026-06-15T11:59:23.310461Z","steps":["trace[992184310] 'process raft request' (duration: 139.75732ms)","trace[992184310] 'compare' (duration: 34.695365ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:59:23.521909Z","caller":"traceutil/trace.go:171","msg":"trace[161380060] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"119.213641ms","start":"2026-06-15T11:59:23.402651Z","end":"2026-06-15T11:59:23.521864Z","steps":["trace[161380060] 'process raft request' (duration: 118.68112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.490312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.106579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:59:24.490858Z","caller":"traceutil/trace.go:171","msg":"trace[1676789690] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"461.867811ms","start":"2026-06-15T11:59:24.028947Z","end":"2026-06-15T11:59:24.490815Z","steps":["trace[1676789690] 'process raft request' (duration: 81.16488ms)","trace[1676789690] 'compare' (duration: 379.790833ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:59:24.490894Z","caller":"traceutil/trace.go:171","msg":"trace[478708221] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9159; }","duration":"460.185669ms","start":"2026-06-15T11:59:24.030663Z","end":"2026-06-15T11:59:24.490848Z","steps":["trace[478708221] 'read index received' (duration: 79.407596ms)","trace[478708221] 'applied index is now lower than readState.Index' (duration: 380.771463ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:59:24.490945Z","caller":"traceutil/trace.go:171","msg":"trace[1856175768] transaction","detail":"{read_only:false; response_revision:8840; number_of_response:1; }","duration":"154.253792ms","start":"2026-06-15T11:59:24.336673Z","end":"2026-06-15T11:59:24.490926Z","steps":["trace[1856175768] 'process raft request' (duration: 154.210482ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:24.491008Z","caller":"traceutil/trace.go:171","msg":"trace[1793411522] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"206.49436ms","start":"2026-06-15T11:59:24.284474Z","end":"2026-06-15T11:59:24.490968Z","steps":["trace[1793411522] 'process raft request' (duration: 206.300627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.491054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:59:24.02893Z","time spent":"462.020994ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:59:24.491085Z","caller":"traceutil/trace.go:171","msg":"trace[66161030] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"156.171701ms","start":"2026-06-15T11:59:24.334892Z","end":"2026-06-15T11:59:24.491064Z","steps":["trace[66161030] 'process raft request' (duration: 155.954257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.491151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.876928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:59:24.491196Z","caller":"traceutil/trace.go:171","msg":"trace[310304199] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8840; }","duration":"375.99978ms","start":"2026-06-15T11:59:24.115181Z","end":"2026-06-15T11:59:24.491181Z","steps":["trace[310304199] 'agreement among raft nodes before linearized reading' (duration: 375.868448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.491231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.992207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T11:59:24.491241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:59:24.115163Z","time spent":"376.067061ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-15T11:59:24.491276Z","caller":"traceutil/trace.go:171","msg":"trace[1873913372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8840; }","duration":"136.09283ms","start":"2026-06-15T11:59:24.355168Z","end":"2026-06-15T11:59:24.49126Z","steps":["trace[1873913372] 'agreement among raft nodes before linearized reading' (duration: 136.023738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.491241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.545316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"warn","ts":"2026-06-15T11:59:24.491329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.723407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-15T11:59:24.491326Z","caller":"traceutil/trace.go:171","msg":"trace[957202619] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8840; }","duration":"460.660698ms","start":"2026-06-15T11:59:24.030649Z","end":"2026-06-15T11:59:24.491309Z","steps":["trace[957202619] 'agreement among raft nodes before linearized reading' (duration: 460.381852ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:24.491369Z","caller":"traceutil/trace.go:171","msg":"trace[1027113175] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8840; }","duration":"164.782928ms","start":"2026-06-15T11:59:24.326573Z","end":"2026-06-15T11:59:24.491356Z","steps":["trace[1027113175] 'agreement among raft nodes before linearized reading' (duration: 164.632966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:59:24.491375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:59:24.030641Z","time spent":"460.723809ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3981,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-06-15T11:59:24.492138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.760493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-06-15T11:59:24.492191Z","caller":"traceutil/trace.go:171","msg":"trace[266980151] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8840; }","duration":"211.864165ms","start":"2026-06-15T11:59:24.280313Z","end":"2026-06-15T11:59:24.492177Z","steps":["trace[266980151] 'agreement among raft nodes before linearized reading' (duration: 210.910936ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:25.123133Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cf.wal"} {"level":"info","ts":"2026-06-15T12:00:48.848143Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T12:00:48.856613Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-15T12:00:48.856888Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-15T12:03:04.607815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8137} {"level":"info","ts":"2026-06-15T12:03:04.678959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8137,"took":"67.449072ms","hash":1393945179,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":27746304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-15T12:03:04.679037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1393945179,"revision":8137,"compact-revision":6070} {"level":"info","ts":"2026-06-15T12:06:01.405371Z","caller":"traceutil/trace.go:171","msg":"trace[1491902250] transaction","detail":"{read_only:false; response_revision:11953; number_of_response:1; }","duration":"100.423878ms","start":"2026-06-15T12:06:01.304895Z","end":"2026-06-15T12:06:01.405319Z","steps":["trace[1491902250] 'process raft request' (duration: 99.941579ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:18.673966Z","caller":"traceutil/trace.go:171","msg":"trace[966598894] transaction","detail":"{read_only:false; response_revision:12052; number_of_response:1; }","duration":"118.001539ms","start":"2026-06-15T12:06:18.55594Z","end":"2026-06-15T12:06:18.673942Z","steps":["trace[966598894] 'process raft request' (duration: 117.868557ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:18.881127Z","caller":"traceutil/trace.go:171","msg":"trace[2143254700] transaction","detail":"{read_only:false; response_revision:12053; number_of_response:1; }","duration":"201.237314ms","start":"2026-06-15T12:06:18.679845Z","end":"2026-06-15T12:06:18.881083Z","steps":["trace[2143254700] 'process raft request' (duration: 181.634954ms)","trace[2143254700] 'compare' (duration: 19.206443ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:06:19.666267Z","caller":"traceutil/trace.go:171","msg":"trace[676914959] linearizableReadLoop","detail":"{readStateIndex:12476; appliedIndex:12475; }","duration":"125.671248ms","start":"2026-06-15T12:06:19.540569Z","end":"2026-06-15T12:06:19.66624Z","steps":["trace[676914959] 'read index received' (duration: 125.514355ms)","trace[676914959] 'applied index is now lower than readState.Index' (duration: 155.123µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:06:19.666331Z","caller":"traceutil/trace.go:171","msg":"trace[823435463] transaction","detail":"{read_only:false; response_revision:12055; number_of_response:1; }","duration":"130.773987ms","start":"2026-06-15T12:06:19.535513Z","end":"2026-06-15T12:06:19.666287Z","steps":["trace[823435463] 'process raft request' (duration: 130.555773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:19.666597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.005135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T12:06:19.666696Z","caller":"traceutil/trace.go:171","msg":"trace[1986624100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12055; }","duration":"126.155028ms","start":"2026-06-15T12:06:19.540514Z","end":"2026-06-15T12:06:19.666669Z","steps":["trace[1986624100] 'agreement among raft nodes before linearized reading' (duration: 125.886483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:19.666852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.148698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T12:06:19.666896Z","caller":"traceutil/trace.go:171","msg":"trace[137775241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12055; }","duration":"126.23039ms","start":"2026-06-15T12:06:19.540653Z","end":"2026-06-15T12:06:19.666884Z","steps":["trace[137775241] 'agreement among raft nodes before linearized reading' (duration: 125.7579ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:20.1284Z","caller":"traceutil/trace.go:171","msg":"trace[400928928] transaction","detail":"{read_only:false; response_revision:12059; number_of_response:1; }","duration":"149.124573ms","start":"2026-06-15T12:06:19.979238Z","end":"2026-06-15T12:06:20.128363Z","steps":["trace[400928928] 'process raft request' (duration: 148.93201ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:08:04.614792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10509} {"level":"info","ts":"2026-06-15T12:08:04.704159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10509,"took":"86.009019ms","hash":305848273,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":27140096,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-15T12:08:04.704255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":305848273,"revision":10509,"compact-revision":8137} {"level":"info","ts":"2026-06-15T12:12:25.263282Z","caller":"traceutil/trace.go:171","msg":"trace[1127346050] transaction","detail":"{read_only:false; response_revision:14527; number_of_response:1; }","duration":"109.017643ms","start":"2026-06-15T12:12:25.154231Z","end":"2026-06-15T12:12:25.263248Z","steps":["trace[1127346050] 'process raft request' (duration: 108.796069ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:12:47.826179Z","caller":"traceutil/trace.go:171","msg":"trace[1591536878] transaction","detail":"{read_only:false; response_revision:14648; number_of_response:1; }","duration":"139.128817ms","start":"2026-06-15T12:12:47.687029Z","end":"2026-06-15T12:12:47.826158Z","steps":["trace[1591536878] 'process raft request' (duration: 138.885613ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:12:47.82628Z","caller":"traceutil/trace.go:171","msg":"trace[1453660869] transaction","detail":"{read_only:false; response_revision:14649; number_of_response:1; }","duration":"138.527614ms","start":"2026-06-15T12:12:47.687686Z","end":"2026-06-15T12:12:47.826214Z","steps":["trace[1453660869] 'process raft request' (duration: 138.357461ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:12:47.826352Z","caller":"traceutil/trace.go:171","msg":"trace[358831572] transaction","detail":"{read_only:false; response_revision:14650; number_of_response:1; }","duration":"138.113177ms","start":"2026-06-15T12:12:47.688215Z","end":"2026-06-15T12:12:47.826328Z","steps":["trace[358831572] 'process raft request' (duration: 137.879242ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:12:47.826364Z","caller":"traceutil/trace.go:171","msg":"trace[1149822418] linearizableReadLoop","detail":"{readStateIndex:15161; appliedIndex:15158; }","duration":"103.042057ms","start":"2026-06-15T12:12:47.72327Z","end":"2026-06-15T12:12:47.826312Z","steps":["trace[1149822418] 'read index received' (duration: 102.579458ms)","trace[1149822418] 'applied index is now lower than readState.Index' (duration: 460.189µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:12:47.826642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.349873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T12:12:47.826745Z","caller":"traceutil/trace.go:171","msg":"trace[172914531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14650; }","duration":"103.559687ms","start":"2026-06-15T12:12:47.723164Z","end":"2026-06-15T12:12:47.826723Z","steps":["trace[172914531] 'agreement among raft nodes before linearized reading' (duration: 103.258471ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:13:04.588196Z","caller":"traceutil/trace.go:171","msg":"trace[1089346831] linearizableReadLoop","detail":"{readStateIndex:15272; appliedIndex:15271; }","duration":"232.291381ms","start":"2026-06-15T12:13:04.355883Z","end":"2026-06-15T12:13:04.588175Z","steps":["trace[1089346831] 'read index received' (duration: 232.106747ms)","trace[1089346831] 'applied index is now lower than readState.Index' (duration: 183.484µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:13:04.588321Z","caller":"traceutil/trace.go:171","msg":"trace[39126794] transaction","detail":"{read_only:false; response_revision:14755; number_of_response:1; }","duration":"252.021974ms","start":"2026-06-15T12:13:04.336267Z","end":"2026-06-15T12:13:04.588289Z","steps":["trace[39126794] 'process raft request' (duration: 251.744248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:04.58844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.513466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:13:04.588552Z","caller":"traceutil/trace.go:171","msg":"trace[336612410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14755; }","duration":"232.676989ms","start":"2026-06-15T12:13:04.35585Z","end":"2026-06-15T12:13:04.588527Z","steps":["trace[336612410] 'agreement among raft nodes before linearized reading' (duration: 232.457995ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:13:04.930314Z","caller":"traceutil/trace.go:171","msg":"trace[577106493] transaction","detail":"{read_only:false; response_revision:14756; number_of_response:1; }","duration":"313.856732ms","start":"2026-06-15T12:13:04.616426Z","end":"2026-06-15T12:13:04.930283Z","steps":["trace[577106493] 'process raft request' (duration: 283.088376ms)","trace[577106493] 'compare' (duration: 30.4489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:13:04.930476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:13:04.61639Z","time spent":"314.007116ms","remote":"127.0.0.1:44400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:13:04.938688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12770} {"level":"info","ts":"2026-06-15T12:13:05.023685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12770,"took":"81.611132ms","hash":762100026,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":27369472,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-15T12:13:05.023757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":762100026,"revision":12770,"compact-revision":10509} {"level":"info","ts":"2026-06-15T12:13:06.564382Z","caller":"traceutil/trace.go:171","msg":"trace[1802651528] transaction","detail":"{read_only:false; response_revision:14771; number_of_response:1; }","duration":"204.323249ms","start":"2026-06-15T12:13:06.360022Z","end":"2026-06-15T12:13:06.564346Z","steps":["trace[1802651528] 'process raft request' (duration: 204.131676ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:13:06.580767Z","caller":"traceutil/trace.go:171","msg":"trace[1687380470] linearizableReadLoop","detail":"{readStateIndex:15290; appliedIndex:15288; }","duration":"183.66578ms","start":"2026-06-15T12:13:06.397068Z","end":"2026-06-15T12:13:06.580734Z","steps":["trace[1687380470] 'read index received' (duration: 167.14026ms)","trace[1687380470] 'applied index is now lower than readState.Index' (duration: 16.52425ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:13:06.580869Z","caller":"traceutil/trace.go:171","msg":"trace[1845599304] transaction","detail":"{read_only:false; response_revision:14772; number_of_response:1; }","duration":"208.98846ms","start":"2026-06-15T12:13:06.371853Z","end":"2026-06-15T12:13:06.580842Z","steps":["trace[1845599304] 'process raft request' (duration: 208.625152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:06.580994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.910974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-15T12:13:06.581038Z","caller":"traceutil/trace.go:171","msg":"trace[905533882] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14772; }","duration":"183.975736ms","start":"2026-06-15T12:13:06.397051Z","end":"2026-06-15T12:13:06.581027Z","steps":["trace[905533882] 'agreement among raft nodes before linearized reading' (duration: 183.804892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:14.725484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.175062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:13:14.725559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.69066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-2swwc\" ","response":"range_response_count:1 size:7032"} {"level":"warn","ts":"2026-06-15T12:13:14.72563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.191754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-15T12:13:14.725677Z","caller":"traceutil/trace.go:171","msg":"trace[227853777] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-2swwc; range_end:; response_count:1; response_revision:14923; }","duration":"249.887284ms","start":"2026-06-15T12:13:14.475762Z","end":"2026-06-15T12:13:14.72565Z","steps":["trace[227853777] 'range keys from in-memory index tree' (duration: 249.423935ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:13:14.725703Z","caller":"traceutil/trace.go:171","msg":"trace[922683191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14923; }","duration":"402.315887ms","start":"2026-06-15T12:13:14.323371Z","end":"2026-06-15T12:13:14.725687Z","steps":["trace[922683191] 'range keys from in-memory index tree' (duration: 401.96565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:14.72575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:13:14.323347Z","time spent":"402.390538ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T12:13:14.725935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.621503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:13:14.726028Z","caller":"traceutil/trace.go:171","msg":"trace[48155334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14923; }","duration":"368.824287ms","start":"2026-06-15T12:13:14.357178Z","end":"2026-06-15T12:13:14.726002Z","steps":["trace[48155334] 'range keys from in-memory index tree' (duration: 368.41078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:14.726079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:13:14.357148Z","time spent":"368.91836ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T12:13:14.725609Z","caller":"traceutil/trace.go:171","msg":"trace[452254347] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:14923; }","duration":"245.333215ms","start":"2026-06-15T12:13:14.480253Z","end":"2026-06-15T12:13:14.725586Z","steps":["trace[452254347] 'count revisions from in-memory index tree' (duration: 245.075479ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:13:14.75001Z","caller":"traceutil/trace.go:171","msg":"trace[803230696] linearizableReadLoop","detail":"{readStateIndex:15445; appliedIndex:15444; }","duration":"115.806885ms","start":"2026-06-15T12:13:14.634182Z","end":"2026-06-15T12:13:14.749989Z","steps":["trace[803230696] 'read index received' (duration: 115.707903ms)","trace[803230696] 'applied index is now lower than readState.Index' (duration: 93.532µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:13:14.750122Z","caller":"traceutil/trace.go:171","msg":"trace[481302492] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"239.522732ms","start":"2026-06-15T12:13:14.510586Z","end":"2026-06-15T12:13:14.750108Z","steps":["trace[481302492] 'process raft request' (duration: 239.202146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:13:14.750433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.232463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-rxkx7.18b9401686e64062\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-15T12:13:14.750536Z","caller":"traceutil/trace.go:171","msg":"trace[486911620] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-rxkx7.18b9401686e64062; range_end:; response_count:1; response_revision:14924; }","duration":"116.353156ms","start":"2026-06-15T12:13:14.634157Z","end":"2026-06-15T12:13:14.75051Z","steps":["trace[486911620] 'agreement among raft nodes before linearized reading' (duration: 116.08069ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:21.229028Z","caller":"traceutil/trace.go:171","msg":"trace[732197663] linearizableReadLoop","detail":"{readStateIndex:16385; appliedIndex:16384; }","duration":"109.422869ms","start":"2026-06-15T12:14:21.119574Z","end":"2026-06-15T12:14:21.228997Z","steps":["trace[732197663] 'read index received' (duration: 109.112872ms)","trace[732197663] 'applied index is now lower than readState.Index' (duration: 308.387µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:14:21.229137Z","caller":"traceutil/trace.go:171","msg":"trace[396246543] transaction","detail":"{read_only:false; response_revision:15840; number_of_response:1; }","duration":"137.980772ms","start":"2026-06-15T12:14:21.091119Z","end":"2026-06-15T12:14:21.2291Z","steps":["trace[396246543] 'process raft request' (duration: 137.668466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:21.229368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.98858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:21.229432Z","caller":"traceutil/trace.go:171","msg":"trace[960250007] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15840; }","duration":"108.102693ms","start":"2026-06-15T12:14:21.121315Z","end":"2026-06-15T12:14:21.229417Z","steps":["trace[960250007] 'agreement among raft nodes before linearized reading' (duration: 107.9769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:21.229464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.857598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-15T12:14:21.229595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.084567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-15T12:14:21.229536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.474849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:14:21.229797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.01055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:21.230017Z","caller":"traceutil/trace.go:171","msg":"trace[1347400793] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15840; }","duration":"110.026351ms","start":"2026-06-15T12:14:21.119521Z","end":"2026-06-15T12:14:21.229548Z","steps":["trace[1347400793] 'agreement among raft nodes before linearized reading' (duration: 109.662264ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:21.230151Z","caller":"traceutil/trace.go:171","msg":"trace[1729459356] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15840; }","duration":"103.177968ms","start":"2026-06-15T12:14:21.126471Z","end":"2026-06-15T12:14:21.229649Z","steps":["trace[1729459356] 'agreement among raft nodes before linearized reading' (duration: 102.949754ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:21.230294Z","caller":"traceutil/trace.go:171","msg":"trace[899898220] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15840; }","duration":"108.698973ms","start":"2026-06-15T12:14:21.121024Z","end":"2026-06-15T12:14:21.229723Z","steps":["trace[899898220] 'agreement among raft nodes before linearized reading' (duration: 108.367007ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:21.230446Z","caller":"traceutil/trace.go:171","msg":"trace[1579799304] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15840; }","duration":"109.141883ms","start":"2026-06-15T12:14:21.120726Z","end":"2026-06-15T12:14:21.229868Z","steps":["trace[1579799304] 'agreement among raft nodes before linearized reading' (duration: 108.482571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.393487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.453752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:44.393599Z","caller":"traceutil/trace.go:171","msg":"trace[471936867] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:15959; }","duration":"119.609645ms","start":"2026-06-15T12:14:44.273966Z","end":"2026-06-15T12:14:44.393576Z","steps":["trace[471936867] 'count revisions from in-memory index tree' (duration: 119.366251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.393781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.080487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxdm6\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-15T12:14:44.39389Z","caller":"traceutil/trace.go:171","msg":"trace[1629753134] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxdm6; range_end:; response_count:1; response_revision:15959; }","duration":"145.237671ms","start":"2026-06-15T12:14:44.248628Z","end":"2026-06-15T12:14:44.393866Z","steps":["trace[1629753134] 'range keys from in-memory index tree' (duration: 144.884054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.394451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.753076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7911"} {"level":"info","ts":"2026-06-15T12:14:44.394559Z","caller":"traceutil/trace.go:171","msg":"trace[1236162770] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:15959; }","duration":"123.733305ms","start":"2026-06-15T12:14:44.270801Z","end":"2026-06-15T12:14:44.394534Z","steps":["trace[1236162770] 'range keys from in-memory index tree' (duration: 122.528872ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:44.56273Z","caller":"traceutil/trace.go:171","msg":"trace[495824047] transaction","detail":"{read_only:false; response_revision:15961; number_of_response:1; }","duration":"252.205103ms","start":"2026-06-15T12:14:44.310494Z","end":"2026-06-15T12:14:44.562699Z","steps":["trace[495824047] 'process raft request' (duration: 245.612005ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:44.564366Z","caller":"traceutil/trace.go:171","msg":"trace[174957048] linearizableReadLoop","detail":"{readStateIndex:16512; appliedIndex:16509; }","duration":"214.447151ms","start":"2026-06-15T12:14:44.349864Z","end":"2026-06-15T12:14:44.564311Z","steps":["trace[174957048] 'read index received' (duration: 35.067408ms)","trace[174957048] 'applied index is now lower than readState.Index' (duration: 179.375303ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:14:44.564791Z","caller":"traceutil/trace.go:171","msg":"trace[898071242] transaction","detail":"{read_only:false; response_revision:15962; number_of_response:1; }","duration":"254.16738ms","start":"2026-06-15T12:14:44.310588Z","end":"2026-06-15T12:14:44.564755Z","steps":["trace[898071242] 'process raft request' (duration: 251.990668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.565465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.865037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:14:44.565477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.170971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-15T12:14:44.5655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.839947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-15T12:14:44.565559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.038128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:44.565592Z","caller":"traceutil/trace.go:171","msg":"trace[854746497] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15962; }","duration":"208.372104ms","start":"2026-06-15T12:14:44.35719Z","end":"2026-06-15T12:14:44.565562Z","steps":["trace[854746497] 'agreement among raft nodes before linearized reading' (duration: 207.908946ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:44.56565Z","caller":"traceutil/trace.go:171","msg":"trace[1953818263] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15962; }","duration":"204.02994ms","start":"2026-06-15T12:14:44.361599Z","end":"2026-06-15T12:14:44.565629Z","steps":["trace[1953818263] 'agreement among raft nodes before linearized reading' (duration: 203.630673ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:44.565559Z","caller":"traceutil/trace.go:171","msg":"trace[1679230764] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15962; }","duration":"203.98353ms","start":"2026-06-15T12:14:44.361554Z","end":"2026-06-15T12:14:44.565537Z","steps":["trace[1679230764] 'agreement among raft nodes before linearized reading' (duration: 203.847057ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:44.565714Z","caller":"traceutil/trace.go:171","msg":"trace[268894257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15962; }","duration":"208.246351ms","start":"2026-06-15T12:14:44.357429Z","end":"2026-06-15T12:14:44.565675Z","steps":["trace[268894257] 'agreement among raft nodes before linearized reading' (duration: 207.986776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.565634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.782637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:44.56631Z","caller":"traceutil/trace.go:171","msg":"trace[943702772] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15962; }","duration":"216.513292ms","start":"2026-06-15T12:14:44.349781Z","end":"2026-06-15T12:14:44.566294Z","steps":["trace[943702772] 'agreement among raft nodes before linearized reading' (duration: 215.37434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.566304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.5477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-06-15T12:14:44.56638Z","caller":"traceutil/trace.go:171","msg":"trace[695211527] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15962; }","duration":"204.654313ms","start":"2026-06-15T12:14:44.361712Z","end":"2026-06-15T12:14:44.566366Z","steps":["trace[695211527] 'agreement among raft nodes before linearized reading' (duration: 203.577592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.569593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.396529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:777260"} {"level":"info","ts":"2026-06-15T12:14:44.56966Z","caller":"traceutil/trace.go:171","msg":"trace[1575372738] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:15962; }","duration":"170.512911ms","start":"2026-06-15T12:14:44.399132Z","end":"2026-06-15T12:14:44.569645Z","steps":["trace[1575372738] 'agreement among raft nodes before linearized reading' (duration: 166.054675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:44.57182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.019418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:416186"} {"level":"info","ts":"2026-06-15T12:14:44.571979Z","caller":"traceutil/trace.go:171","msg":"trace[431914755] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:15962; }","duration":"207.192221ms","start":"2026-06-15T12:14:44.364768Z","end":"2026-06-15T12:14:44.57196Z","steps":["trace[431914755] 'agreement among raft nodes before linearized reading' (duration: 200.356149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:45.728838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.476083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:14:45.72894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.302835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-86w6t\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-06-15T12:14:45.728975Z","caller":"traceutil/trace.go:171","msg":"trace[584993029] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15971; }","duration":"261.645096ms","start":"2026-06-15T12:14:45.467304Z","end":"2026-06-15T12:14:45.728949Z","steps":["trace[584993029] 'range keys from in-memory index tree' (duration: 261.421811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:45.729005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.734059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-15T12:14:45.729043Z","caller":"traceutil/trace.go:171","msg":"trace[741691061] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-86w6t; range_end:; response_count:1; response_revision:15971; }","duration":"126.495139ms","start":"2026-06-15T12:14:45.602524Z","end":"2026-06-15T12:14:45.729019Z","steps":["trace[741691061] 'range keys from in-memory index tree' (duration: 126.04422ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:14:45.72906Z","caller":"traceutil/trace.go:171","msg":"trace[912107508] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15971; }","duration":"258.80846ms","start":"2026-06-15T12:14:45.470236Z","end":"2026-06-15T12:14:45.729045Z","steps":["trace[912107508] 'range keys from in-memory index tree' (duration: 258.601827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:45.729313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.813469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18722"} {"level":"info","ts":"2026-06-15T12:14:45.72942Z","caller":"traceutil/trace.go:171","msg":"trace[1194089916] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:15971; }","duration":"322.949321ms","start":"2026-06-15T12:14:45.406448Z","end":"2026-06-15T12:14:45.729397Z","steps":["trace[1194089916] 'range keys from in-memory index tree' (duration: 322.583235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:45.729423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.428993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-15T12:14:45.729463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:14:45.406439Z","time spent":"323.015453ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18745,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-15T12:14:45.729475Z","caller":"traceutil/trace.go:171","msg":"trace[1689565116] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15971; }","duration":"241.493575ms","start":"2026-06-15T12:14:45.487966Z","end":"2026-06-15T12:14:45.729459Z","steps":["trace[1689565116] 'range keys from in-memory index tree' (duration: 241.339842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:14:45.729731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.13162ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:14:45.729834Z","caller":"traceutil/trace.go:171","msg":"trace[1961590328] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15971; }","duration":"238.237743ms","start":"2026-06-15T12:14:45.491582Z","end":"2026-06-15T12:14:45.72982Z","steps":["trace[1961590328] 'range keys from in-memory index tree' (duration: 238.12151ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:18:04.945734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14756} {"level":"info","ts":"2026-06-15T12:18:05.032978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14756,"took":"83.259802ms","hash":40380293,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":34304000,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-15T12:18:05.033063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":40380293,"revision":14756,"compact-revision":12770} {"level":"info","ts":"2026-06-15T12:20:30.44162Z","caller":"traceutil/trace.go:171","msg":"trace[996684908] transaction","detail":"{read_only:false; response_revision:18488; number_of_response:1; }","duration":"157.005248ms","start":"2026-06-15T12:20:30.28459Z","end":"2026-06-15T12:20:30.441595Z","steps":["trace[996684908] 'process raft request' (duration: 156.863515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:36.822615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.254376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T12:20:36.82273Z","caller":"traceutil/trace.go:171","msg":"trace[133517613] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:18528; }","duration":"120.581293ms","start":"2026-06-15T12:20:36.702125Z","end":"2026-06-15T12:20:36.822706Z","steps":["trace[133517613] 'count revisions from in-memory index tree' (duration: 120.100353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:37.75816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.827742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-15T12:20:37.758276Z","caller":"traceutil/trace.go:171","msg":"trace[1749034013] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18529; }","duration":"252.070177ms","start":"2026-06-15T12:20:37.506176Z","end":"2026-06-15T12:20:37.758246Z","steps":["trace[1749034013] 'range keys from in-memory index tree' (duration: 251.566846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:37.758622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.04188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9mdkh\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-06-15T12:20:37.758663Z","caller":"traceutil/trace.go:171","msg":"trace[761061793] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9mdkh; range_end:; response_count:1; response_revision:18529; }","duration":"278.109451ms","start":"2026-06-15T12:20:37.480543Z","end":"2026-06-15T12:20:37.758652Z","steps":["trace[761061793] 'range keys from in-memory index tree' (duration: 277.869326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:37.758952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.771158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T12:20:37.759012Z","caller":"traceutil/trace.go:171","msg":"trace[1537275828] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18529; }","duration":"241.839319ms","start":"2026-06-15T12:20:37.517159Z","end":"2026-06-15T12:20:37.758998Z","steps":["trace[1537275828] 'count revisions from in-memory index tree' (duration: 241.491102ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:20:38.06478Z","caller":"traceutil/trace.go:171","msg":"trace[429057288] linearizableReadLoop","detail":"{readStateIndex:19167; appliedIndex:19166; }","duration":"312.965365ms","start":"2026-06-15T12:20:37.751775Z","end":"2026-06-15T12:20:38.064741Z","steps":["trace[429057288] 'read index received' (duration: 312.68131ms)","trace[429057288] 'applied index is now lower than readState.Index' (duration: 282.995µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:20:38.064929Z","caller":"traceutil/trace.go:171","msg":"trace[817015340] transaction","detail":"{read_only:false; response_revision:18530; number_of_response:1; }","duration":"372.753142ms","start":"2026-06-15T12:20:37.692163Z","end":"2026-06-15T12:20:38.064916Z","steps":["trace[817015340] 'process raft request' (duration: 372.384635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:38.065054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:20:37.692134Z","time spent":"372.822503ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:20:38.065559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.438684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T12:20:38.065952Z","caller":"traceutil/trace.go:171","msg":"trace[761283937] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:18530; }","duration":"314.217969ms","start":"2026-06-15T12:20:37.751708Z","end":"2026-06-15T12:20:38.065926Z","steps":["trace[761283937] 'agreement among raft nodes before linearized reading' (duration: 313.450305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:38.066007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:20:37.751678Z","time spent":"314.31324ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:20:38.090636Z","caller":"traceutil/trace.go:171","msg":"trace[616750718] transaction","detail":"{read_only:false; response_revision:18532; number_of_response:1; }","duration":"142.505387ms","start":"2026-06-15T12:20:37.948078Z","end":"2026-06-15T12:20:38.090584Z","steps":["trace[616750718] 'process raft request' (duration: 141.62536ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:20:38.090752Z","caller":"traceutil/trace.go:171","msg":"trace[973510863] transaction","detail":"{read_only:false; response_revision:18531; number_of_response:1; }","duration":"281.341583ms","start":"2026-06-15T12:20:37.809379Z","end":"2026-06-15T12:20:38.090721Z","steps":["trace[973510863] 'process raft request' (duration: 280.17486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:38.090885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.302957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-15T12:20:38.090981Z","caller":"traceutil/trace.go:171","msg":"trace[1491122241] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:18534; }","duration":"196.46546ms","start":"2026-06-15T12:20:37.894498Z","end":"2026-06-15T12:20:38.090963Z","steps":["trace[1491122241] 'agreement among raft nodes before linearized reading' (duration: 196.165355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:38.091142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.348504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:948648"} {"level":"info","ts":"2026-06-15T12:20:38.091244Z","caller":"traceutil/trace.go:171","msg":"trace[260951628] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18534; }","duration":"326.503556ms","start":"2026-06-15T12:20:37.764717Z","end":"2026-06-15T12:20:38.091221Z","steps":["trace[260951628] 'agreement among raft nodes before linearized reading' (duration: 325.089849ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:20:38.090661Z","caller":"traceutil/trace.go:171","msg":"trace[1595083293] transaction","detail":"{read_only:false; response_revision:18533; number_of_response:1; }","duration":"101.689178ms","start":"2026-06-15T12:20:37.988931Z","end":"2026-06-15T12:20:38.09062Z","steps":["trace[1595083293] 'process raft request' (duration: 100.807231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:20:38.091311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:20:37.764704Z","time spent":"326.592749ms","remote":"127.0.0.1:44590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":96,"response size":948671,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-15T12:20:38.257421Z","caller":"traceutil/trace.go:171","msg":"trace[1869768653] transaction","detail":"{read_only:false; response_revision:18535; number_of_response:1; }","duration":"128.511766ms","start":"2026-06-15T12:20:38.128872Z","end":"2026-06-15T12:20:38.257383Z","steps":["trace[1869768653] 'process raft request' (duration: 128.231141ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:40.737194Z","caller":"traceutil/trace.go:171","msg":"trace[1765737875] transaction","detail":"{read_only:false; response_revision:19289; number_of_response:1; }","duration":"100.779623ms","start":"2026-06-15T12:22:40.636376Z","end":"2026-06-15T12:22:40.737155Z","steps":["trace[1765737875] 'process raft request' (duration: 100.734663ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:40.737311Z","caller":"traceutil/trace.go:171","msg":"trace[1683245650] transaction","detail":"{read_only:false; response_revision:19288; number_of_response:1; }","duration":"101.046758ms","start":"2026-06-15T12:22:40.636161Z","end":"2026-06-15T12:22:40.737208Z","steps":["trace[1683245650] 'process raft request' (duration: 100.906886ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:40.738144Z","caller":"traceutil/trace.go:171","msg":"trace[1678726719] transaction","detail":"{read_only:false; response_revision:19287; number_of_response:1; }","duration":"102.285822ms","start":"2026-06-15T12:22:40.635836Z","end":"2026-06-15T12:22:40.738122Z","steps":["trace[1678726719] 'process raft request' (duration: 101.053698ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:41.412108Z","caller":"traceutil/trace.go:171","msg":"trace[2054540539] transaction","detail":"{read_only:false; response_revision:19291; number_of_response:1; }","duration":"111.028611ms","start":"2026-06-15T12:22:41.301055Z","end":"2026-06-15T12:22:41.412084Z","steps":["trace[2054540539] 'process raft request' (duration: 110.895318ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:41.427165Z","caller":"traceutil/trace.go:171","msg":"trace[1193505532] transaction","detail":"{read_only:false; response_revision:19292; number_of_response:1; }","duration":"125.924558ms","start":"2026-06-15T12:22:41.301213Z","end":"2026-06-15T12:22:41.427138Z","steps":["trace[1193505532] 'process raft request' (duration: 125.417589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:42.340411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.217066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-15T12:22:42.340545Z","caller":"traceutil/trace.go:171","msg":"trace[1351953053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19294; }","duration":"221.405099ms","start":"2026-06-15T12:22:42.119116Z","end":"2026-06-15T12:22:42.340521Z","steps":["trace[1351953053] 'range keys from in-memory index tree' (duration: 220.980421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:42.340649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.548233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-15T12:22:42.340767Z","caller":"traceutil/trace.go:171","msg":"trace[746436461] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19294; }","duration":"219.697586ms","start":"2026-06-15T12:22:42.121044Z","end":"2026-06-15T12:22:42.340742Z","steps":["trace[746436461] 'range keys from in-memory index tree' (duration: 219.310418ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:44.94092Z","caller":"traceutil/trace.go:171","msg":"trace[1124763491] transaction","detail":"{read_only:false; response_revision:19307; number_of_response:1; }","duration":"113.806504ms","start":"2026-06-15T12:22:44.827078Z","end":"2026-06-15T12:22:44.940884Z","steps":["trace[1124763491] 'process raft request' (duration: 113.5401ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:44.941085Z","caller":"traceutil/trace.go:171","msg":"trace[1125424407] transaction","detail":"{read_only:false; response_revision:19308; number_of_response:1; }","duration":"113.209163ms","start":"2026-06-15T12:22:44.82783Z","end":"2026-06-15T12:22:44.941039Z","steps":["trace[1125424407] 'process raft request' (duration: 112.994588ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:45.771451Z","caller":"traceutil/trace.go:171","msg":"trace[1190779764] transaction","detail":"{read_only:false; response_revision:19313; number_of_response:1; }","duration":"126.811466ms","start":"2026-06-15T12:22:45.644594Z","end":"2026-06-15T12:22:45.771406Z","steps":["trace[1190779764] 'process raft request' (duration: 78.771659ms)","trace[1190779764] 'compare' (duration: 47.843453ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:47.051815Z","caller":"traceutil/trace.go:171","msg":"trace[691260482] transaction","detail":"{read_only:false; response_revision:19318; number_of_response:1; }","duration":"104.499015ms","start":"2026-06-15T12:22:46.947287Z","end":"2026-06-15T12:22:47.051786Z","steps":["trace[691260482] 'process raft request' (duration: 104.315922ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:47.053643Z","caller":"traceutil/trace.go:171","msg":"trace[634159822] transaction","detail":"{read_only:false; response_revision:19319; number_of_response:1; }","duration":"106.112135ms","start":"2026-06-15T12:22:46.9475Z","end":"2026-06-15T12:22:47.053612Z","steps":["trace[634159822] 'process raft request' (duration: 105.940872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:47.719451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.853893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-06-15T12:22:47.719476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.975004ms","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-06-15T12:22:47.719543Z","caller":"traceutil/trace.go:171","msg":"trace[2083791655] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19320; }","duration":"214.993327ms","start":"2026-06-15T12:22:47.504529Z","end":"2026-06-15T12:22:47.719522Z","steps":["trace[2083791655] 'range keys from in-memory index tree' (duration: 214.652609ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:47.719577Z","caller":"traceutil/trace.go:171","msg":"trace[675682365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19320; }","duration":"174.127247ms","start":"2026-06-15T12:22:47.54543Z","end":"2026-06-15T12:22:47.719557Z","steps":["trace[675682365] 'range keys from in-memory index tree' (duration: 173.783641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:47.71955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.891157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-15T12:22:47.719675Z","caller":"traceutil/trace.go:171","msg":"trace[652637386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19320; }","duration":"197.081941ms","start":"2026-06-15T12:22:47.522556Z","end":"2026-06-15T12:22:47.719638Z","steps":["trace[652637386] 'range keys from in-memory index tree' (duration: 196.579371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:47.719835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.14929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-15T12:22:47.719888Z","caller":"traceutil/trace.go:171","msg":"trace[330831990] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19320; }","duration":"213.254582ms","start":"2026-06-15T12:22:47.506619Z","end":"2026-06-15T12:22:47.719873Z","steps":["trace[330831990] 'range keys from in-memory index tree' (duration: 212.990537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:48.196037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.515839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T12:22:48.196148Z","caller":"traceutil/trace.go:171","msg":"trace[2096106634] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:19324; }","duration":"112.661022ms","start":"2026-06-15T12:22:48.083455Z","end":"2026-06-15T12:22:48.196116Z","steps":["trace[2096106634] 'count revisions from in-memory index tree' (duration: 112.442638ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:49.448094Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-06-15T12:22:49.558757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.188905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T12:22:49.558899Z","caller":"traceutil/trace.go:171","msg":"trace[457885880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19331; }","duration":"137.328808ms","start":"2026-06-15T12:22:49.421519Z","end":"2026-06-15T12:22:49.558847Z","steps":["trace[457885880] 'agreement among raft nodes before linearized reading' (duration: 26.693295ms)","trace[457885880] 'range keys from in-memory index tree' (duration: 110.45926ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:49.667713Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-15T12:22:49.667872Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-15T12:22:49.932419Z","caller":"traceutil/trace.go:171","msg":"trace[1876991] transaction","detail":"{read_only:false; response_revision:19334; number_of_response:1; }","duration":"119.490284ms","start":"2026-06-15T12:22:49.812894Z","end":"2026-06-15T12:22:49.932384Z","steps":["trace[1876991] 'process raft request' (duration: 81.39936ms)","trace[1876991] 'compare' (duration: 37.944981ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:51.574002Z","caller":"traceutil/trace.go:171","msg":"trace[1105292603] transaction","detail":"{read_only:false; response_revision:19340; number_of_response:1; }","duration":"115.60098ms","start":"2026-06-15T12:22:51.458373Z","end":"2026-06-15T12:22:51.573974Z","steps":["trace[1105292603] 'process raft request' (duration: 115.427847ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:53.28781Z","caller":"traceutil/trace.go:171","msg":"trace[712023407] transaction","detail":"{read_only:false; response_revision:19346; number_of_response:1; }","duration":"324.537958ms","start":"2026-06-15T12:22:52.963238Z","end":"2026-06-15T12:22:53.287776Z","steps":["trace[712023407] 'process raft request' (duration: 324.324005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.287997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:52.963216Z","time spent":"324.681081ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:22:53.443865Z","caller":"traceutil/trace.go:171","msg":"trace[39007520] transaction","detail":"{read_only:false; response_revision:19347; number_of_response:1; }","duration":"423.806251ms","start":"2026-06-15T12:22:53.020017Z","end":"2026-06-15T12:22:53.443823Z","steps":["trace[39007520] 'process raft request' (duration: 423.464735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.444159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:53.019984Z","time spent":"424.013116ms","remote":"127.0.0.1:53766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:22:53.478359Z","caller":"traceutil/trace.go:171","msg":"trace[921270329] transaction","detail":"{read_only:false; response_revision:19348; number_of_response:1; }","duration":"355.264991ms","start":"2026-06-15T12:22:53.123064Z","end":"2026-06-15T12:22:53.478329Z","steps":["trace[921270329] 'process raft request' (duration: 355.001706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.478537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:53.123041Z","time spent":"355.407933ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:22:53.478635Z","caller":"traceutil/trace.go:171","msg":"trace[170312583] transaction","detail":"{read_only:false; response_revision:19350; number_of_response:1; }","duration":"174.812911ms","start":"2026-06-15T12:22:53.303804Z","end":"2026-06-15T12:22:53.478617Z","steps":["trace[170312583] 'process raft request' (duration: 174.709209ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:53.47874Z","caller":"traceutil/trace.go:171","msg":"trace[1105245158] linearizableReadLoop","detail":"{readStateIndex:20019; appliedIndex:20016; }","duration":"428.212486ms","start":"2026-06-15T12:22:53.050499Z","end":"2026-06-15T12:22:53.478712Z","steps":["trace[1105245158] 'read index received' (duration: 237.134272ms)","trace[1105245158] 'applied index is now lower than readState.Index' (duration: 191.074474ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:53.478756Z","caller":"traceutil/trace.go:171","msg":"trace[2001927947] transaction","detail":"{read_only:false; response_revision:19349; number_of_response:1; }","duration":"301.770258ms","start":"2026-06-15T12:22:53.176961Z","end":"2026-06-15T12:22:53.478731Z","steps":["trace[2001927947] 'process raft request' (duration: 301.3126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.478885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.386811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-15T12:22:53.478922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.586009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T12:22:53.478985Z","caller":"traceutil/trace.go:171","msg":"trace[2069393920] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:19350; }","duration":"428.507143ms","start":"2026-06-15T12:22:53.050444Z","end":"2026-06-15T12:22:53.478952Z","steps":["trace[2069393920] 'agreement among raft nodes before linearized reading' (duration: 428.38487ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:53.478987Z","caller":"traceutil/trace.go:171","msg":"trace[1406984093] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:19350; }","duration":"420.705553ms","start":"2026-06-15T12:22:53.058262Z","end":"2026-06-15T12:22:53.478968Z","steps":["trace[1406984093] 'agreement among raft nodes before linearized reading' (duration: 420.60198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.479019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.667066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T12:22:53.479051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:53.058238Z","time spent":"420.791775ms","remote":"127.0.0.1:54650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T12:22:53.47906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:53.176936Z","time spent":"301.912821ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:22:53.479071Z","caller":"traceutil/trace.go:171","msg":"trace[2127589997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19350; }","duration":"121.754318ms","start":"2026-06-15T12:22:53.357299Z","end":"2026-06-15T12:22:53.479053Z","steps":["trace[2127589997] 'agreement among raft nodes before linearized reading' (duration: 121.663067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:53.479076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:22:53.050424Z","time spent":"428.641827ms","remote":"127.0.0.1:56952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:22:54.094735Z","caller":"traceutil/trace.go:171","msg":"trace[485631634] transaction","detail":"{read_only:false; response_revision:19354; number_of_response:1; }","duration":"117.628248ms","start":"2026-06-15T12:22:53.97708Z","end":"2026-06-15T12:22:54.094709Z","steps":["trace[485631634] 'process raft request' (duration: 103.613088ms)","trace[485631634] 'compare' (duration: 13.860297ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:55.41226Z","caller":"traceutil/trace.go:171","msg":"trace[1875703816] transaction","detail":"{read_only:false; response_revision:19358; number_of_response:1; }","duration":"117.696189ms","start":"2026-06-15T12:22:55.294522Z","end":"2026-06-15T12:22:55.412218Z","steps":["trace[1875703816] 'process raft request' (duration: 117.339853ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:55.589846Z","caller":"traceutil/trace.go:171","msg":"trace[1111217478] transaction","detail":"{read_only:false; response_revision:19360; number_of_response:1; }","duration":"104.144368ms","start":"2026-06-15T12:22:55.48567Z","end":"2026-06-15T12:22:55.589814Z","steps":["trace[1111217478] 'process raft request' (duration: 104.087697ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:55.590055Z","caller":"traceutil/trace.go:171","msg":"trace[643504918] transaction","detail":"{read_only:false; response_revision:19359; number_of_response:1; }","duration":"104.476574ms","start":"2026-06-15T12:22:55.485564Z","end":"2026-06-15T12:22:55.590041Z","steps":["trace[643504918] 'process raft request' (duration: 45.381325ms)","trace[643504918] 'compare' (duration: 58.681622ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:56.207543Z","caller":"traceutil/trace.go:171","msg":"trace[234523149] linearizableReadLoop","detail":"{readStateIndex:20036; appliedIndex:20035; }","duration":"107.886641ms","start":"2026-06-15T12:22:56.099619Z","end":"2026-06-15T12:22:56.207506Z","steps":["trace[234523149] 'read index received' (duration: 107.658196ms)","trace[234523149] 'applied index is now lower than readState.Index' (duration: 226.415µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:56.207637Z","caller":"traceutil/trace.go:171","msg":"trace[1846446082] transaction","detail":"{read_only:false; response_revision:19364; number_of_response:1; }","duration":"145.459614ms","start":"2026-06-15T12:22:56.062141Z","end":"2026-06-15T12:22:56.207601Z","steps":["trace[1846446082] 'process raft request' (duration: 145.150489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:56.207868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.246608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-15T12:22:56.207933Z","caller":"traceutil/trace.go:171","msg":"trace[675404147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19364; }","duration":"108.37736ms","start":"2026-06-15T12:22:56.099537Z","end":"2026-06-15T12:22:56.207914Z","steps":["trace[675404147] 'agreement among raft nodes before linearized reading' (duration: 108.168155ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:58.397066Z","caller":"traceutil/trace.go:171","msg":"trace[834543499] transaction","detail":"{read_only:false; response_revision:19375; number_of_response:1; }","duration":"179.687054ms","start":"2026-06-15T12:22:58.217299Z","end":"2026-06-15T12:22:58.396986Z","steps":["trace[834543499] 'process raft request' (duration: 179.348118ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:58.516092Z","caller":"traceutil/trace.go:171","msg":"trace[1775234413] transaction","detail":"{read_only:false; response_revision:19377; number_of_response:1; }","duration":"113.309245ms","start":"2026-06-15T12:22:58.402754Z","end":"2026-06-15T12:22:58.516063Z","steps":["trace[1775234413] 'process raft request' (duration: 113.263304ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:58.516129Z","caller":"traceutil/trace.go:171","msg":"trace[504707029] transaction","detail":"{read_only:false; response_revision:19376; number_of_response:1; }","duration":"125.365886ms","start":"2026-06-15T12:22:58.390722Z","end":"2026-06-15T12:22:58.516088Z","steps":["trace[504707029] 'process raft request' (duration: 54.827767ms)","trace[504707029] 'compare' (duration: 70.315335ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:22:58.516351Z","caller":"traceutil/trace.go:171","msg":"trace[756601866] linearizableReadLoop","detail":"{readStateIndex:20049; appliedIndex:20048; }","duration":"118.911283ms","start":"2026-06-15T12:22:58.397406Z","end":"2026-06-15T12:22:58.516318Z","steps":["trace[756601866] 'read index received' (duration: 48.157719ms)","trace[756601866] 'applied index is now lower than readState.Index' (duration: 70.749904ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:22:58.516596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.117127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bj87l\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-06-15T12:22:58.51665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.351953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T12:22:58.516703Z","caller":"traceutil/trace.go:171","msg":"trace[41932653] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bj87l; range_end:; response_count:1; response_revision:19377; }","duration":"187.306442ms","start":"2026-06-15T12:22:58.329382Z","end":"2026-06-15T12:22:58.516689Z","steps":["trace[41932653] 'agreement among raft nodes before linearized reading' (duration: 187.052126ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:58.516714Z","caller":"traceutil/trace.go:171","msg":"trace[842465414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19377; }","duration":"160.442854ms","start":"2026-06-15T12:22:58.356257Z","end":"2026-06-15T12:22:58.516699Z","steps":["trace[842465414] 'agreement among raft nodes before linearized reading' (duration: 160.3125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:22:58.516661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.82786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-15T12:22:58.516874Z","caller":"traceutil/trace.go:171","msg":"trace[367938805] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19377; }","duration":"134.082915ms","start":"2026-06-15T12:22:58.382754Z","end":"2026-06-15T12:22:58.516837Z","steps":["trace[367938805] 'agreement among raft nodes before linearized reading' (duration: 133.740718ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:22:59.776704Z","caller":"traceutil/trace.go:171","msg":"trace[230054707] transaction","detail":"{read_only:false; response_revision:19382; number_of_response:1; }","duration":"100.236922ms","start":"2026-06-15T12:22:59.676405Z","end":"2026-06-15T12:22:59.776642Z","steps":["trace[230054707] 'process raft request' (duration: 32.089099ms)","trace[230054707] 'compare' (duration: 67.918349ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:23:00.655578Z","caller":"traceutil/trace.go:171","msg":"trace[765080876] linearizableReadLoop","detail":"{readStateIndex:20059; appliedIndex:20058; }","duration":"117.328962ms","start":"2026-06-15T12:23:00.538223Z","end":"2026-06-15T12:23:00.655552Z","steps":["trace[765080876] 'read index received' (duration: 117.109938ms)","trace[765080876] 'applied index is now lower than readState.Index' (duration: 218.144µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:23:00.65572Z","caller":"traceutil/trace.go:171","msg":"trace[79212886] transaction","detail":"{read_only:false; response_revision:19386; number_of_response:1; }","duration":"130.731211ms","start":"2026-06-15T12:23:00.524945Z","end":"2026-06-15T12:23:00.655676Z","steps":["trace[79212886] 'process raft request' (duration: 130.440155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:23:00.655805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.554947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bj87l\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-06-15T12:23:00.65585Z","caller":"traceutil/trace.go:171","msg":"trace[992936264] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bj87l; range_end:; response_count:1; response_revision:19386; }","duration":"117.632999ms","start":"2026-06-15T12:23:00.538206Z","end":"2026-06-15T12:23:00.655839Z","steps":["trace[992936264] 'agreement among raft nodes before linearized reading' (duration: 117.458725ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:23:03.100801Z","caller":"traceutil/trace.go:171","msg":"trace[502628322] linearizableReadLoop","detail":"{readStateIndex:20070; appliedIndex:20069; }","duration":"135.422631ms","start":"2026-06-15T12:23:02.965347Z","end":"2026-06-15T12:23:03.100769Z","steps":["trace[502628322] 'read index received' (duration: 135.189486ms)","trace[502628322] 'applied index is now lower than readState.Index' (duration: 231.125µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:23:03.100977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.594765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T12:23:03.101026Z","caller":"traceutil/trace.go:171","msg":"trace[139939509] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:19397; }","duration":"135.680727ms","start":"2026-06-15T12:23:02.965329Z","end":"2026-06-15T12:23:03.10101Z","steps":["trace[139939509] 'agreement among raft nodes before linearized reading' (duration: 135.557614ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:23:03.101069Z","caller":"traceutil/trace.go:171","msg":"trace[250271273] transaction","detail":"{read_only:false; response_revision:19397; number_of_response:1; }","duration":"256.20754ms","start":"2026-06-15T12:23:02.844836Z","end":"2026-06-15T12:23:03.101044Z","steps":["trace[250271273] 'process raft request' (duration: 255.740231ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:23:03.127599Z","caller":"traceutil/trace.go:171","msg":"trace[868414947] transaction","detail":"{read_only:false; response_revision:19398; number_of_response:1; }","duration":"141.548859ms","start":"2026-06-15T12:23:02.986031Z","end":"2026-06-15T12:23:03.12758Z","steps":["trace[868414947] 'process raft request' (duration: 141.278704ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:23:05.034971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17520} {"level":"info","ts":"2026-06-15T12:23:05.35342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17520,"took":"314.126837ms","hash":2969399133,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":30830592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-15T12:23:05.353518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2969399133,"revision":17520,"compact-revision":14756} {"level":"info","ts":"2026-06-15T12:24:24.19245Z","caller":"traceutil/trace.go:171","msg":"trace[97702931] transaction","detail":"{read_only:false; response_revision:19817; number_of_response:1; }","duration":"125.000691ms","start":"2026-06-15T12:24:24.067416Z","end":"2026-06-15T12:24:24.192417Z","steps":["trace[97702931] 'process raft request' (duration: 124.811607ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:28:05.041597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19406} {"level":"info","ts":"2026-06-15T12:28:05.133766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19406,"took":"89.121049ms","hash":1870183923,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":24416256,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-15T12:28:05.133848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1870183923,"revision":19406,"compact-revision":17520}