{"level":"warn","ts":"2026-07-22T22:00:06.180955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:00:06.181058Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-22T22:00:06.18114Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:00:06.181155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-22T22:00:06.181181Z","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-07-22T22:00:06.182399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-22T22:00:06.182498Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-22T22:00:06.187189Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.428369ms"} {"level":"info","ts":"2026-07-22T22:00:06.197394Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-22T22:00:06.197555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:00:06.197612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-22T22:00:06.197629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:00:06.197642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-22T22:00:06.197727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-22T22:00:06.204165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:00:06.205405Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:00:06.20644Z","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-07-22T22:00:06.20837Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:00:06.209185Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:00:06.211891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-22T22:00:06.213202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-22T22:00:06.209188Z","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-07-22T22:00:06.213496Z","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-07-22T22:00:06.213516Z","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-07-22T22:00:06.21418Z","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-07-22T22:00:06.214266Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-22T22:00:06.214296Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-22T22:00:06.214346Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:00:06.214374Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:00:06.298617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:00:06.298671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:00:06.298694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-22T22:00:06.298709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:00:06.298717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-22T22:00:06.298727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-22T22:00:06.298746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-22T22:00:06.299483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:00:06.299485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:00:06.299564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:00:06.299723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:00:06.29991Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:00:06.299937Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:00:06.300582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:00:06.300697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:00:06.300742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:00:06.3015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-22T22:00:06.301729Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-22T22:00:48.994629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.504743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:00:48.994838Z","caller":"traceutil/trace.go:171","msg":"trace[79760692] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"364.445338ms","start":"2026-07-22T22:00:48.630378Z","end":"2026-07-22T22:00:48.994823Z","steps":["trace[79760692] 'read index received' (duration: 5.716621ms)","trace[79760692] 'applied index is now lower than readState.Index' (duration: 358.727677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:48.995005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.625294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:00:48.99504Z","caller":"traceutil/trace.go:171","msg":"trace[1592616925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"364.689436ms","start":"2026-07-22T22:00:48.630342Z","end":"2026-07-22T22:00:48.995031Z","steps":["trace[1592616925] 'agreement among raft nodes before linearized reading' (duration: 364.557692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:48.995075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:48.630328Z","time spent":"364.738497ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:00:48.994949Z","caller":"traceutil/trace.go:171","msg":"trace[76719512] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"364.665945ms","start":"2026-07-22T22:00:48.630194Z","end":"2026-07-22T22:00:48.99486Z","steps":["trace[76719512] 'process raft request' (duration: 116.389168ms)","trace[76719512] 'compare' (duration: 247.358929ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:48.995504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:48.630183Z","time spent":"365.103398ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:00:49.23261Z","caller":"traceutil/trace.go:171","msg":"trace[23043863] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"237.70136ms","start":"2026-07-22T22:00:48.994875Z","end":"2026-07-22T22:00:49.232576Z","steps":["trace[23043863] 'read index received' (duration: 229.451806ms)","trace[23043863] 'applied index is now lower than readState.Index' (duration: 8.248164ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:49.232772Z","caller":"traceutil/trace.go:171","msg":"trace[1741456307] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"400.14704ms","start":"2026-07-22T22:00:48.83259Z","end":"2026-07-22T22:00:49.232737Z","steps":["trace[1741456307] 'process raft request' (duration: 391.863445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:49.232999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:48.83257Z","time spent":"400.360507ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:00:49.233002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.400126ms","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-07-22T22:00:49.233034Z","caller":"traceutil/trace.go:171","msg":"trace[969950546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"322.488349ms","start":"2026-07-22T22:00:48.910537Z","end":"2026-07-22T22:00:49.233026Z","steps":["trace[969950546] 'agreement among raft nodes before linearized reading' (duration: 322.154758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:49.233065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:48.910514Z","time spent":"322.54538ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:00:49.243753Z","caller":"traceutil/trace.go:171","msg":"trace[150578161] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"196.165999ms","start":"2026-07-22T22:00:49.047571Z","end":"2026-07-22T22:00:49.243737Z","steps":["trace[150578161] 'process raft request' (duration: 196.134408ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:49.243892Z","caller":"traceutil/trace.go:171","msg":"trace[266727197] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"244.191314ms","start":"2026-07-22T22:00:48.999678Z","end":"2026-07-22T22:00:49.24387Z","steps":["trace[266727197] 'process raft request' (duration: 243.902575ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:49.813949Z","caller":"traceutil/trace.go:171","msg":"trace[813496795] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"147.710832ms","start":"2026-07-22T22:00:49.66621Z","end":"2026-07-22T22:00:49.813921Z","steps":["trace[813496795] 'read index received' (duration: 147.448534ms)","trace[813496795] 'applied index is now lower than readState.Index' (duration: 261.058µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:49.814015Z","caller":"traceutil/trace.go:171","msg":"trace[1587851136] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"522.814228ms","start":"2026-07-22T22:00:49.291179Z","end":"2026-07-22T22:00:49.813993Z","steps":["trace[1587851136] 'process raft request' (duration: 522.577541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:49.814121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:49.291149Z","time spent":"522.918611ms","remote":"127.0.0.1:60530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:00:49.814717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.494585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:49.814789Z","caller":"traceutil/trace.go:171","msg":"trace[897854976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"148.613489ms","start":"2026-07-22T22:00:49.666161Z","end":"2026-07-22T22:00:49.814774Z","steps":["trace[897854976] 'agreement among raft nodes before linearized reading' (duration: 147.929799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:01:15.753947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.610786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:01:15.754178Z","caller":"traceutil/trace.go:171","msg":"trace[2052586002] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:807; }","duration":"239.826212ms","start":"2026-07-22T22:01:15.514231Z","end":"2026-07-22T22:01:15.754057Z","steps":["trace[2052586002] 'count revisions from in-memory index tree' (duration: 239.457762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:05.183147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.794289ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-22T22:02:05.183799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.442205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-22T22:02:05.183851Z","caller":"traceutil/trace.go:171","msg":"trace[923269188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"144.540718ms","start":"2026-07-22T22:02:05.039296Z","end":"2026-07-22T22:02:05.183837Z","steps":["trace[923269188] 'agreement among raft nodes before linearized reading' (duration: 144.350303ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:05.183561Z","caller":"traceutil/trace.go:171","msg":"trace[1218162479] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"144.19942ms","start":"2026-07-22T22:02:05.039341Z","end":"2026-07-22T22:02:05.18354Z","steps":["trace[1218162479] 'read index received' (duration: 36.751µs)","trace[1218162479] 'applied index is now lower than readState.Index' (duration: 144.160518ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:02:05.276379Z","caller":"traceutil/trace.go:171","msg":"trace[1237967844] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"234.961741ms","start":"2026-07-22T22:02:05.041396Z","end":"2026-07-22T22:02:05.276358Z","steps":["trace[1237967844] 'process raft request' (duration: 234.813708ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:05.342456Z","caller":"traceutil/trace.go:171","msg":"trace[1048210359] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"153.454842ms","start":"2026-07-22T22:02:05.188914Z","end":"2026-07-22T22:02:05.342369Z","steps":["trace[1048210359] 'process raft request' (duration: 153.053663ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:06.097969Z","caller":"traceutil/trace.go:171","msg":"trace[1782265689] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"119.399793ms","start":"2026-07-22T22:02:05.978542Z","end":"2026-07-22T22:02:06.097942Z","steps":["trace[1782265689] 'process raft request' (duration: 118.924892ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:11.466123Z","caller":"traceutil/trace.go:171","msg":"trace[546135360] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"102.669964ms","start":"2026-07-22T22:02:11.363433Z","end":"2026-07-22T22:02:11.466103Z","steps":["trace[546135360] 'read index received' (duration: 102.51441ms)","trace[546135360] 'applied index is now lower than readState.Index' (duration: 154.884µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:02:11.466276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.825707ms","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-07-22T22:02:11.466303Z","caller":"traceutil/trace.go:171","msg":"trace[2139814283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"102.895319ms","start":"2026-07-22T22:02:11.3634Z","end":"2026-07-22T22:02:11.466295Z","steps":["trace[2139814283] 'agreement among raft nodes before linearized reading' (duration: 102.774846ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:11.466381Z","caller":"traceutil/trace.go:171","msg":"trace[541058749] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"169.741384ms","start":"2026-07-22T22:02:11.296599Z","end":"2026-07-22T22:02:11.46634Z","steps":["trace[541058749] 'process raft request' (duration: 169.395496ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:11.811136Z","caller":"traceutil/trace.go:171","msg":"trace[134626692] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"341.540796ms","start":"2026-07-22T22:02:11.469568Z","end":"2026-07-22T22:02:11.811109Z","steps":["trace[134626692] 'process raft request' (duration: 341.383472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:11.811288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:02:11.469544Z","time spent":"341.663529ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:02:11.819239Z","caller":"traceutil/trace.go:171","msg":"trace[1884132170] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1425; }","duration":"319.413721ms","start":"2026-07-22T22:02:11.499798Z","end":"2026-07-22T22:02:11.819212Z","steps":["trace[1884132170] 'read index received' (duration: 311.136595ms)","trace[1884132170] 'applied index is now lower than readState.Index' (duration: 8.276046ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:02:11.81929Z","caller":"traceutil/trace.go:171","msg":"trace[2000269343] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"346.79073ms","start":"2026-07-22T22:02:11.472477Z","end":"2026-07-22T22:02:11.819268Z","steps":["trace[2000269343] 'process raft request' (duration: 346.463632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:11.819468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:02:11.472462Z","time spent":"346.972575ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:02:11.819532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.522555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:02:11.819801Z","caller":"traceutil/trace.go:171","msg":"trace[1388419010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"152.840402ms","start":"2026-07-22T22:02:11.666947Z","end":"2026-07-22T22:02:11.819787Z","steps":["trace[1388419010] 'agreement among raft nodes before linearized reading' (duration: 152.527665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:11.819902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.191525ms","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-07-22T22:02:11.81997Z","caller":"traceutil/trace.go:171","msg":"trace[809408342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"146.318328ms","start":"2026-07-22T22:02:11.673634Z","end":"2026-07-22T22:02:11.819952Z","steps":["trace[809408342] 'agreement among raft nodes before linearized reading' (duration: 145.868618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:11.820291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.526894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-22T22:02:11.820316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.505837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:02:11.820336Z","caller":"traceutil/trace.go:171","msg":"trace[653349223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"141.587346ms","start":"2026-07-22T22:02:11.678741Z","end":"2026-07-22T22:02:11.820328Z","steps":["trace[653349223] 'agreement among raft nodes before linearized reading' (duration: 141.499734ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:11.82035Z","caller":"traceutil/trace.go:171","msg":"trace[1820940249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"320.591879ms","start":"2026-07-22T22:02:11.499749Z","end":"2026-07-22T22:02:11.820341Z","steps":["trace[1820940249] 'agreement among raft nodes before linearized reading' (duration: 319.587916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:11.820376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:02:11.499737Z","time spent":"320.63271ms","remote":"127.0.0.1:60566","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":"warn","ts":"2026-07-22T22:02:41.215144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.010703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-22T22:02:41.215269Z","caller":"traceutil/trace.go:171","msg":"trace[1323431415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"274.195857ms","start":"2026-07-22T22:02:40.941049Z","end":"2026-07-22T22:02:41.215245Z","steps":["trace[1323431415] 'range keys from in-memory index tree' (duration: 273.700436ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:41.362614Z","caller":"traceutil/trace.go:171","msg":"trace[1352112927] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"138.261ms","start":"2026-07-22T22:02:41.224295Z","end":"2026-07-22T22:02:41.362556Z","steps":["trace[1352112927] 'process raft request' (duration: 138.186998ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:41.362666Z","caller":"traceutil/trace.go:171","msg":"trace[67698243] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"142.614159ms","start":"2026-07-22T22:02:41.219995Z","end":"2026-07-22T22:02:41.362609Z","steps":["trace[67698243] 'process raft request' (duration: 131.922474ms)","trace[67698243] 'compare' (duration: 10.417529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:02:41.629278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.110114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-22T22:02:41.629374Z","caller":"traceutil/trace.go:171","msg":"trace[1632790983] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"123.277488ms","start":"2026-07-22T22:02:41.506068Z","end":"2026-07-22T22:02:41.629346Z","steps":["trace[1632790983] 'range keys from in-memory index tree' (duration: 122.94308ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:47.798973Z","caller":"traceutil/trace.go:171","msg":"trace[1500577013] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"132.490982ms","start":"2026-07-22T22:02:47.666458Z","end":"2026-07-22T22:02:47.798949Z","steps":["trace[1500577013] 'read index received' (duration: 132.266507ms)","trace[1500577013] 'applied index is now lower than readState.Index' (duration: 223.555µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:02:47.799102Z","caller":"traceutil/trace.go:171","msg":"trace[1289544964] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"148.222769ms","start":"2026-07-22T22:02:47.650837Z","end":"2026-07-22T22:02:47.79906Z","steps":["trace[1289544964] 'process raft request' (duration: 147.948863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:02:47.799241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.767128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:02:47.799304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.312256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-22T22:02:47.799322Z","caller":"traceutil/trace.go:171","msg":"trace[1445498471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1969; }","duration":"132.924691ms","start":"2026-07-22T22:02:47.666374Z","end":"2026-07-22T22:02:47.799299Z","steps":["trace[1445498471] 'agreement among raft nodes before linearized reading' (duration: 132.709597ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:02:47.799374Z","caller":"traceutil/trace.go:171","msg":"trace[1968946661] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1969; }","duration":"113.392867ms","start":"2026-07-22T22:02:47.685941Z","end":"2026-07-22T22:02:47.799333Z","steps":["trace[1968946661] 'agreement among raft nodes before linearized reading' (duration: 113.130521ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:14.483295Z","caller":"traceutil/trace.go:171","msg":"trace[248700448] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"104.826926ms","start":"2026-07-22T22:03:14.378432Z","end":"2026-07-22T22:03:14.483259Z","steps":["trace[248700448] 'process raft request' (duration: 104.640072ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:15.287878Z","caller":"traceutil/trace.go:171","msg":"trace[1897211371] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"115.738797ms","start":"2026-07-22T22:03:15.17211Z","end":"2026-07-22T22:03:15.287848Z","steps":["trace[1897211371] 'read index received' (duration: 115.41868ms)","trace[1897211371] 'applied index is now lower than readState.Index' (duration: 318.447µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:15.288249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.113255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-07-22T22:03:15.288249Z","caller":"traceutil/trace.go:171","msg":"trace[661092869] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"229.078661ms","start":"2026-07-22T22:03:15.059146Z","end":"2026-07-22T22:03:15.288225Z","steps":["trace[661092869] 'process raft request' (duration: 228.532079ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:15.288326Z","caller":"traceutil/trace.go:171","msg":"trace[220074911] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2141; }","duration":"116.241318ms","start":"2026-07-22T22:03:15.172054Z","end":"2026-07-22T22:03:15.288296Z","steps":["trace[220074911] 'agreement among raft nodes before linearized reading' (duration: 115.899671ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:15.757662Z","caller":"traceutil/trace.go:171","msg":"trace[981911907] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"189.07022ms","start":"2026-07-22T22:03:15.568555Z","end":"2026-07-22T22:03:15.757626Z","steps":["trace[981911907] 'process raft request' (duration: 89.314339ms)","trace[981911907] 'compare' (duration: 99.166749ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:15.75807Z","caller":"traceutil/trace.go:171","msg":"trace[1777737862] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"150.969472ms","start":"2026-07-22T22:03:15.607061Z","end":"2026-07-22T22:03:15.758031Z","steps":["trace[1777737862] 'process raft request' (duration: 150.472571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.125125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342224545270525,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:04:15.248661Z","caller":"traceutil/trace.go:171","msg":"trace[635757726] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"749.128537ms","start":"2026-07-22T22:04:14.499329Z","end":"2026-07-22T22:04:15.248457Z","steps":["trace[635757726] 'process raft request' (duration: 748.996695ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:15.249625Z","caller":"traceutil/trace.go:171","msg":"trace[844135623] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"749.216621ms","start":"2026-07-22T22:04:14.500384Z","end":"2026-07-22T22:04:15.249601Z","steps":["trace[844135623] 'process raft request' (duration: 748.70867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.250495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.499319Z","time spent":"751.078351ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:04:15.250682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.500368Z","time spent":"750.202261ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:15.249712Z","caller":"traceutil/trace.go:171","msg":"trace[884405442] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"522.845712ms","start":"2026-07-22T22:04:14.726818Z","end":"2026-07-22T22:04:15.249664Z","steps":["trace[884405442] 'process raft request' (duration: 522.356032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.250971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.726796Z","time spent":"524.13337ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:15.249695Z","caller":"traceutil/trace.go:171","msg":"trace[971978685] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"342.495986ms","start":"2026-07-22T22:04:14.907184Z","end":"2026-07-22T22:04:15.24968Z","steps":["trace[971978685] 'process raft request' (duration: 342.041597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.251095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.907167Z","time spent":"343.908566ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:15.251747Z","caller":"traceutil/trace.go:171","msg":"trace[1055268474] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2615; }","duration":"627.447327ms","start":"2026-07-22T22:04:14.624289Z","end":"2026-07-22T22:04:15.251736Z","steps":["trace[1055268474] 'read index received' (duration: 624.251488ms)","trace[1055268474] 'applied index is now lower than readState.Index' (duration: 3.194989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:04:15.252101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.809617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:04:15.252137Z","caller":"traceutil/trace.go:171","msg":"trace[487011169] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2517; }","duration":"627.90288ms","start":"2026-07-22T22:04:14.624224Z","end":"2026-07-22T22:04:15.252127Z","steps":["trace[487011169] 'agreement among raft nodes before linearized reading' (duration: 627.829198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.252161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.624171Z","time spent":"627.983071ms","remote":"127.0.0.1:37358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:04:15.252346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.217208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:04:15.252374Z","caller":"traceutil/trace.go:171","msg":"trace[1094984136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2517; }","duration":"381.28161ms","start":"2026-07-22T22:04:14.871085Z","end":"2026-07-22T22:04:15.252367Z","steps":["trace[1094984136] 'agreement among raft nodes before linearized reading' (duration: 381.242189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:15.252393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:14.871066Z","time spent":"381.32269ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:04:16.120664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342224545270534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:04:16.621661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342224545270534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:04:16.717032Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.113728028s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:04:16.71738Z","caller":"traceutil/trace.go:171","msg":"trace[655524478] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"1.114150067s","start":"2026-07-22T22:04:15.603209Z","end":"2026-07-22T22:04:16.717359Z","steps":["trace[655524478] 'process raft request' (duration: 1.113969413s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:16.717517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:15.603193Z","time spent":"1.114231969s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:04:17.75545Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038179417s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:04:17.757654Z","caller":"traceutil/trace.go:171","msg":"trace[1305954129] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"2.112058138s","start":"2026-07-22T22:04:15.644975Z","end":"2026-07-22T22:04:17.757033Z","steps":["trace[1305954129] 'process raft request' (duration: 2.110877182s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:17.757687Z","caller":"traceutil/trace.go:171","msg":"trace[1752584471] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"1.613306464s","start":"2026-07-22T22:04:16.144346Z","end":"2026-07-22T22:04:17.757653Z","steps":["trace[1752584471] 'process raft request' (duration: 1.6131336s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:17.757701Z","caller":"traceutil/trace.go:171","msg":"trace[1248258733] linearizableReadLoop","detail":"{readStateIndex:2622; appliedIndex:2619; }","duration":"2.137375689s","start":"2026-07-22T22:04:15.620303Z","end":"2026-07-22T22:04:17.757679Z","steps":["trace[1248258733] 'read index received' (duration: 1.096978003s)","trace[1248258733] 'applied index is now lower than readState.Index' (duration: 1.040395546s)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:04:17.757823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:15.644958Z","time spent":"2.112784044s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:04:17.757853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.144318Z","time spent":"1.613435476s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:17.757701Z","caller":"traceutil/trace.go:171","msg":"trace[606773963] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"500.168022ms","start":"2026-07-22T22:04:17.257505Z","end":"2026-07-22T22:04:17.757673Z","steps":["trace[606773963] 'process raft request' (duration: 500.08392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668976744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-22T22:04:17.758019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.137667015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:04:17.758117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090997847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:04:17.758145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257601592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:04:17.758153Z","caller":"traceutil/trace.go:171","msg":"trace[492203385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2522; }","duration":"1.091071358s","start":"2026-07-22T22:04:16.667071Z","end":"2026-07-22T22:04:17.758142Z","steps":["trace[492203385] 'agreement among raft nodes before linearized reading' (duration: 1.091030847s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:17.75818Z","caller":"traceutil/trace.go:171","msg":"trace[1718470586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2522; }","duration":"1.257675284s","start":"2026-07-22T22:04:16.500497Z","end":"2026-07-22T22:04:17.758172Z","steps":["trace[1718470586] 'agreement among raft nodes before linearized reading' (duration: 1.257588202s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.667051Z","time spent":"1.091128839s","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:04:17.758191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.951519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:04:17.758136Z","caller":"traceutil/trace.go:171","msg":"trace[137178259] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2522; }","duration":"2.13784391s","start":"2026-07-22T22:04:15.620272Z","end":"2026-07-22T22:04:17.758116Z","steps":["trace[137178259] 'agreement among raft nodes before linearized reading' (duration: 2.137467242s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:17.758224Z","caller":"traceutil/trace.go:171","msg":"trace[671498050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2522; }","duration":"501.00233ms","start":"2026-07-22T22:04:17.25721Z","end":"2026-07-22T22:04:17.758212Z","steps":["trace[671498050] 'agreement among raft nodes before linearized reading' (duration: 500.894707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:17.257199Z","time spent":"501.048041ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:04:17.758255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:15.620261Z","time spent":"2.137975332s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:04:17.758289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258249936s","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-07-22T22:04:17.758333Z","caller":"traceutil/trace.go:171","msg":"trace[1178154474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2522; }","duration":"1.258336158s","start":"2026-07-22T22:04:16.499987Z","end":"2026-07-22T22:04:17.758323Z","steps":["trace[1178154474] 'agreement among raft nodes before linearized reading' (duration: 1.258241626s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.49997Z","time spent":"1.25839788s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:04:17.75821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.500473Z","time spent":"1.257731376s","remote":"127.0.0.1:60566","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-07-22T22:04:17.758067Z","caller":"traceutil/trace.go:171","msg":"trace[1255648409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2522; }","duration":"1.669066896s","start":"2026-07-22T22:04:16.088986Z","end":"2026-07-22T22:04:17.758053Z","steps":["trace[1255648409] 'agreement among raft nodes before linearized reading' (duration: 1.668910783s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.336163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:04:17.758508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:17.257485Z","time spent":"500.549931ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:17.757923Z","caller":"traceutil/trace.go:171","msg":"trace[1572383868] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"500.275004ms","start":"2026-07-22T22:04:17.257624Z","end":"2026-07-22T22:04:17.757899Z","steps":["trace[1572383868] 'process raft request' (duration: 500.006698ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:17.75856Z","caller":"traceutil/trace.go:171","msg":"trace[6692192] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2522; }","duration":"880.873335ms","start":"2026-07-22T22:04:16.877666Z","end":"2026-07-22T22:04:17.758539Z","steps":["trace[6692192] 'agreement among raft nodes before linearized reading' (duration: 880.345513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:17.257615Z","time spent":"501.02494ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:04:17.758714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.980531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-22T22:04:17.758688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.877646Z","time spent":"881.027868ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":21,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:04:17.758769Z","caller":"traceutil/trace.go:171","msg":"trace[1078324194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2522; }","duration":"502.083963ms","start":"2026-07-22T22:04:17.25667Z","end":"2026-07-22T22:04:17.758754Z","steps":["trace[1078324194] 'agreement among raft nodes before linearized reading' (duration: 501.758606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.088955Z","time spent":"1.669520126s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:04:17.758821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:17.256646Z","time spent":"502.163876ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:04:17.758083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097240403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:04:17.758906Z","caller":"traceutil/trace.go:171","msg":"trace[527474940] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2522; }","duration":"1.098113512s","start":"2026-07-22T22:04:16.660777Z","end":"2026-07-22T22:04:17.75889Z","steps":["trace[527474940] 'agreement among raft nodes before linearized reading' (duration: 1.097260744s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:17.758994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:16.660755Z","time spent":"1.098221374s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:06:38.865499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.181582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-22T22:06:38.865591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.658952ms","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-07-22T22:06:38.865617Z","caller":"traceutil/trace.go:171","msg":"trace[2101863165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3319; }","duration":"215.341626ms","start":"2026-07-22T22:06:38.65025Z","end":"2026-07-22T22:06:38.865591Z","steps":["trace[2101863165] 'range keys from in-memory index tree' (duration: 215.009569ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:38.865825Z","caller":"traceutil/trace.go:171","msg":"trace[1043140537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3319; }","duration":"214.919508ms","start":"2026-07-22T22:06:38.650885Z","end":"2026-07-22T22:06:38.865805Z","steps":["trace[1043140537] 'range keys from in-memory index tree' (duration: 214.473848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:38.866316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.294365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-22T22:06:38.866395Z","caller":"traceutil/trace.go:171","msg":"trace[1978493851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3319; }","duration":"232.435777ms","start":"2026-07-22T22:06:38.63394Z","end":"2026-07-22T22:06:38.866376Z","steps":["trace[1978493851] 'range keys from in-memory index tree' (duration: 232.136181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:43.281384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.707819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-22T22:06:43.281461Z","caller":"traceutil/trace.go:171","msg":"trace[236958418] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3353; }","duration":"132.821781ms","start":"2026-07-22T22:06:43.148622Z","end":"2026-07-22T22:06:43.281444Z","steps":["trace[236958418] 'range keys from in-memory index tree' (duration: 132.504965ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:44.86105Z","caller":"traceutil/trace.go:171","msg":"trace[1639296592] transaction","detail":"{read_only:false; response_revision:3356; number_of_response:1; }","duration":"530.612351ms","start":"2026-07-22T22:06:44.330412Z","end":"2026-07-22T22:06:44.861024Z","steps":["trace[1639296592] 'process raft request' (duration: 530.435397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:44.861324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:44.330391Z","time spent":"530.731833ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:06:44.863974Z","caller":"traceutil/trace.go:171","msg":"trace[1416138350] transaction","detail":"{read_only:false; response_revision:3357; number_of_response:1; }","duration":"498.90641ms","start":"2026-07-22T22:06:44.365047Z","end":"2026-07-22T22:06:44.863953Z","steps":["trace[1416138350] 'process raft request' (duration: 498.745997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:44.864115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:44.365013Z","time spent":"499.019053ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:06:44.864171Z","caller":"traceutil/trace.go:171","msg":"trace[368301535] linearizableReadLoop","detail":"{readStateIndex:3491; appliedIndex:3488; }","duration":"468.732541ms","start":"2026-07-22T22:06:44.395422Z","end":"2026-07-22T22:06:44.864154Z","steps":["trace[368301535] 'read index received' (duration: 465.396291ms)","trace[368301535] 'applied index is now lower than readState.Index' (duration: 3.33532ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:06:44.864299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.873894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:06:44.864282Z","caller":"traceutil/trace.go:171","msg":"trace[1460206823] transaction","detail":"{read_only:false; response_revision:3358; number_of_response:1; }","duration":"498.168424ms","start":"2026-07-22T22:06:44.366091Z","end":"2026-07-22T22:06:44.86426Z","steps":["trace[1460206823] 'process raft request' (duration: 497.838167ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:44.864341Z","caller":"traceutil/trace.go:171","msg":"trace[1680813150] 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:3358; }","duration":"468.962146ms","start":"2026-07-22T22:06:44.395366Z","end":"2026-07-22T22:06:44.864329Z","steps":["trace[1680813150] 'agreement among raft nodes before linearized reading' (duration: 468.890075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:44.864368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.371728ms","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-07-22T22:06:44.864378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:44.395341Z","time spent":"469.027538ms","remote":"127.0.0.1:38562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:06:44.864392Z","caller":"traceutil/trace.go:171","msg":"trace[998618695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3358; }","duration":"352.433659ms","start":"2026-07-22T22:06:44.511952Z","end":"2026-07-22T22:06:44.864385Z","steps":["trace[998618695] 'agreement among raft nodes before linearized reading' (duration: 352.346667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:44.864417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:44.51193Z","time spent":"352.47973ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:06:44.864432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:44.366077Z","time spent":"498.276207ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:06:44.864519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.386454ms","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-07-22T22:06:44.864546Z","caller":"traceutil/trace.go:171","msg":"trace[1943063295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3358; }","duration":"261.453654ms","start":"2026-07-22T22:06:44.603086Z","end":"2026-07-22T22:06:44.864539Z","steps":["trace[1943063295] 'agreement among raft nodes before linearized reading' (duration: 261.389533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:44.864684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.757937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:06:44.864748Z","caller":"traceutil/trace.go:171","msg":"trace[939313293] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3358; }","duration":"129.87357ms","start":"2026-07-22T22:06:44.734862Z","end":"2026-07-22T22:06:44.864735Z","steps":["trace[939313293] 'agreement among raft nodes before linearized reading' (duration: 129.532151ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:09.094108Z","caller":"traceutil/trace.go:171","msg":"trace[261762059] transaction","detail":"{read_only:false; response_revision:3567; number_of_response:1; }","duration":"105.416466ms","start":"2026-07-22T22:07:08.988672Z","end":"2026-07-22T22:07:09.094088Z","steps":["trace[261762059] 'process raft request' (duration: 105.280283ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:09.094283Z","caller":"traceutil/trace.go:171","msg":"trace[751017559] transaction","detail":"{read_only:false; response_revision:3568; number_of_response:1; }","duration":"104.125178ms","start":"2026-07-22T22:07:08.990151Z","end":"2026-07-22T22:07:09.094276Z","steps":["trace[751017559] 'process raft request' (duration: 103.905333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.100967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.310917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:08:03.10103Z","caller":"traceutil/trace.go:171","msg":"trace[880897218] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:3923; }","duration":"603.42956ms","start":"2026-07-22T22:08:02.497586Z","end":"2026-07-22T22:08:03.101015Z","steps":["trace[880897218] 'count revisions from in-memory index tree' (duration: 603.172275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.101059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:02.497564Z","time spent":"603.488122ms","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:08:03.101155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.930359ms","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-07-22T22:08:03.101227Z","caller":"traceutil/trace.go:171","msg":"trace[1025902057] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3923; }","duration":"236.99809ms","start":"2026-07-22T22:08:02.864217Z","end":"2026-07-22T22:08:03.101215Z","steps":["trace[1025902057] 'range keys from in-memory index tree' (duration: 236.920998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.101223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.056502ms","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:7"} {"level":"info","ts":"2026-07-22T22:08:03.101285Z","caller":"traceutil/trace.go:171","msg":"trace[413290609] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3923; }","duration":"420.168824ms","start":"2026-07-22T22:08:02.681103Z","end":"2026-07-22T22:08:03.101271Z","steps":["trace[413290609] 'count revisions from in-memory index tree' (duration: 419.941539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.101324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:02.681082Z","time spent":"420.232105ms","remote":"127.0.0.1:60480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":21,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:08:03.101474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.707711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-node-exporter-full\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:08:03.101496Z","caller":"traceutil/trace.go:171","msg":"trace[28246784] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-node-exporter-full; range_end:; response_count:0; response_revision:3923; }","duration":"147.763043ms","start":"2026-07-22T22:08:02.953727Z","end":"2026-07-22T22:08:03.10149Z","steps":["trace[28246784] 'range keys from in-memory index tree' (duration: 147.571538ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:03.625917Z","caller":"traceutil/trace.go:171","msg":"trace[489349136] transaction","detail":"{read_only:false; response_revision:3928; number_of_response:1; }","duration":"343.2742ms","start":"2026-07-22T22:08:03.282626Z","end":"2026-07-22T22:08:03.6259Z","steps":["trace[489349136] 'process raft request' (duration: 343.24975ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:03.625925Z","caller":"traceutil/trace.go:171","msg":"trace[1346506186] transaction","detail":"{read_only:false; response_revision:3926; number_of_response:1; }","duration":"408.441115ms","start":"2026-07-22T22:08:03.217454Z","end":"2026-07-22T22:08:03.625895Z","steps":["trace[1346506186] 'process raft request' (duration: 405.789799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.626019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.282599Z","time spent":"343.366792ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:03.626051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.217434Z","time spent":"408.544037ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:08:03.626083Z","caller":"traceutil/trace.go:171","msg":"trace[808053067] transaction","detail":"{read_only:false; response_revision:3927; number_of_response:1; }","duration":"348.088312ms","start":"2026-07-22T22:08:03.27797Z","end":"2026-07-22T22:08:03.626058Z","steps":["trace[808053067] 'process raft request' (duration: 347.848367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.626269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.277926Z","time spent":"348.263855ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:08:03.979254Z","caller":"traceutil/trace.go:171","msg":"trace[967912604] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"320.321341ms","start":"2026-07-22T22:08:03.658913Z","end":"2026-07-22T22:08:03.979234Z","steps":["trace[967912604] 'process raft request' (duration: 320.234779ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:03.979269Z","caller":"traceutil/trace.go:171","msg":"trace[2075246334] transaction","detail":"{read_only:false; response_revision:3929; number_of_response:1; }","duration":"320.406472ms","start":"2026-07-22T22:08:03.65883Z","end":"2026-07-22T22:08:03.979237Z","steps":["trace[2075246334] 'process raft request' (duration: 290.720141ms)","trace[2075246334] 'compare' (duration: 29.312843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:08:03.979361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.65888Z","time spent":"320.422892ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:03.979421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.658816Z","time spent":"320.516845ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:08:03.979459Z","caller":"traceutil/trace.go:171","msg":"trace[768036657] linearizableReadLoop","detail":"{readStateIndex:4090; appliedIndex:4085; }","duration":"316.197403ms","start":"2026-07-22T22:08:03.663238Z","end":"2026-07-22T22:08:03.979435Z","steps":["trace[768036657] 'read index received' (duration: 287.288799ms)","trace[768036657] 'applied index is now lower than readState.Index' (duration: 28.907544ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:08:03.979498Z","caller":"traceutil/trace.go:171","msg":"trace[1558397598] transaction","detail":"{read_only:false; response_revision:3933; number_of_response:1; }","duration":"317.94997ms","start":"2026-07-22T22:08:03.661541Z","end":"2026-07-22T22:08:03.979491Z","steps":["trace[1558397598] 'process raft request' (duration: 317.855708ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:03.979522Z","caller":"traceutil/trace.go:171","msg":"trace[1421925918] transaction","detail":"{read_only:false; response_revision:3931; number_of_response:1; }","duration":"320.481394ms","start":"2026-07-22T22:08:03.659037Z","end":"2026-07-22T22:08:03.979518Z","steps":["trace[1421925918] 'process raft request' (duration: 320.173777ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:03.979485Z","caller":"traceutil/trace.go:171","msg":"trace[1322726960] transaction","detail":"{read_only:false; response_revision:3932; number_of_response:1; }","duration":"320.345232ms","start":"2026-07-22T22:08:03.659109Z","end":"2026-07-22T22:08:03.979454Z","steps":["trace[1322726960] 'process raft request' (duration: 320.178778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.979547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.659027Z","time spent":"320.507204ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:03.979594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.355007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:08:03.979621Z","caller":"traceutil/trace.go:171","msg":"trace[1597710164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3933; }","duration":"316.409278ms","start":"2026-07-22T22:08:03.663203Z","end":"2026-07-22T22:08:03.979612Z","steps":["trace[1597710164] 'agreement among raft nodes before linearized reading' (duration: 316.356957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.979656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.661487Z","time spent":"318.046472ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:03.979659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.659103Z","time spent":"320.452414ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:03.979679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:03.663189Z","time spent":"316.484909ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:08:04.510459Z","caller":"traceutil/trace.go:171","msg":"trace[1715369733] linearizableReadLoop","detail":"{readStateIndex:4091; appliedIndex:4090; }","duration":"238.345028ms","start":"2026-07-22T22:08:04.272085Z","end":"2026-07-22T22:08:04.51043Z","steps":["trace[1715369733] 'read index received' (duration: 238.077132ms)","trace[1715369733] 'applied index is now lower than readState.Index' (duration: 267.046µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:08:04.510507Z","caller":"traceutil/trace.go:171","msg":"trace[1764313236] transaction","detail":"{read_only:false; response_revision:3934; number_of_response:1; }","duration":"461.851101ms","start":"2026-07-22T22:08:04.048619Z","end":"2026-07-22T22:08:04.51047Z","steps":["trace[1764313236] 'process raft request' (duration: 461.647257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:04.510708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:04.048601Z","time spent":"461.998605ms","remote":"127.0.0.1:60404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":34514,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:08:04.510775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.667145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-22T22:08:04.510817Z","caller":"traceutil/trace.go:171","msg":"trace[546678463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3934; }","duration":"238.775139ms","start":"2026-07-22T22:08:04.272028Z","end":"2026-07-22T22:08:04.510803Z","steps":["trace[546678463] 'agreement among raft nodes before linearized reading' (duration: 238.523964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:46.316925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.533118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-22T22:08:46.317014Z","caller":"traceutil/trace.go:171","msg":"trace[1365876828] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4699; }","duration":"167.683541ms","start":"2026-07-22T22:08:46.149312Z","end":"2026-07-22T22:08:46.316995Z","steps":["trace[1365876828] 'range keys from in-memory index tree' (duration: 167.369624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:46.317081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.794454ms","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-07-22T22:08:46.31716Z","caller":"traceutil/trace.go:171","msg":"trace[950806170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4699; }","duration":"106.904376ms","start":"2026-07-22T22:08:46.21024Z","end":"2026-07-22T22:08:46.317144Z","steps":["trace[950806170] 'range keys from in-memory index tree' (duration: 106.61029ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:52.190137Z","caller":"traceutil/trace.go:171","msg":"trace[192605566] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"169.254615ms","start":"2026-07-22T22:08:52.02086Z","end":"2026-07-22T22:08:52.190114Z","steps":["trace[192605566] 'process raft request' (duration: 169.130533ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:52.378348Z","caller":"traceutil/trace.go:171","msg":"trace[89543987] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4996; }","duration":"300.253024ms","start":"2026-07-22T22:08:52.078072Z","end":"2026-07-22T22:08:52.378325Z","steps":["trace[89543987] 'read index received' (duration: 112.353993ms)","trace[89543987] 'applied index is now lower than readState.Index' (duration: 187.897921ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:08:52.37847Z","caller":"traceutil/trace.go:171","msg":"trace[150480118] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"356.310298ms","start":"2026-07-22T22:08:52.022149Z","end":"2026-07-22T22:08:52.378459Z","steps":["trace[150480118] 'process raft request' (duration: 356.036072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:52.378567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:52.022135Z","time spent":"356.36037ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:08:52.378759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.690093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-22T22:08:52.378803Z","caller":"traceutil/trace.go:171","msg":"trace[462712371] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4809; }","duration":"300.729445ms","start":"2026-07-22T22:08:52.078059Z","end":"2026-07-22T22:08:52.378788Z","steps":["trace[462712371] 'agreement among raft nodes before linearized reading' (duration: 300.615093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:52.378919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:08:52.078047Z","time spent":"300.860417ms","remote":"127.0.0.1:60452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-22T22:08:52.379083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.395752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d09ee51-64f2-46f5-8d7c-3f3b081bd66e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-22T22:08:52.379159Z","caller":"traceutil/trace.go:171","msg":"trace[1496462127] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d09ee51-64f2-46f5-8d7c-3f3b081bd66e; range_end:; response_count:1; response_revision:4809; }","duration":"257.495584ms","start":"2026-07-22T22:08:52.121644Z","end":"2026-07-22T22:08:52.37914Z","steps":["trace[1496462127] 'agreement among raft nodes before linearized reading' (duration: 257.29686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:52.379455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.788512ms","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:7"} {"level":"info","ts":"2026-07-22T22:08:52.379501Z","caller":"traceutil/trace.go:171","msg":"trace[583140776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4809; }","duration":"135.836753ms","start":"2026-07-22T22:08:52.243653Z","end":"2026-07-22T22:08:52.37949Z","steps":["trace[583140776] 'agreement among raft nodes before linearized reading' (duration: 135.775331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:52.37956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.807652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-22T22:08:52.379603Z","caller":"traceutil/trace.go:171","msg":"trace[1032677417] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4809; }","duration":"181.882924ms","start":"2026-07-22T22:08:52.19771Z","end":"2026-07-22T22:08:52.379592Z","steps":["trace[1032677417] 'agreement among raft nodes before linearized reading' (duration: 181.788752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:52.846177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.225007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:08:52.846285Z","caller":"traceutil/trace.go:171","msg":"trace[1395230677] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"181.405174ms","start":"2026-07-22T22:08:52.664863Z","end":"2026-07-22T22:08:52.846268Z","steps":["trace[1395230677] 'read index received' (duration: 54.141µs)","trace[1395230677] 'applied index is now lower than readState.Index' (duration: 181.349963ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:08:52.846339Z","caller":"traceutil/trace.go:171","msg":"trace[1515392202] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"290.247843ms","start":"2026-07-22T22:08:52.556052Z","end":"2026-07-22T22:08:52.8463Z","steps":["trace[1515392202] 'process raft request' (duration: 56.832201ms)","trace[1515392202] 'compare' (duration: 233.068484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:08:52.846436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.578457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:08:52.846466Z","caller":"traceutil/trace.go:171","msg":"trace[1541930629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4821; }","duration":"181.66106ms","start":"2026-07-22T22:08:52.664795Z","end":"2026-07-22T22:08:52.846456Z","steps":["trace[1541930629] 'agreement among raft nodes before linearized reading' (duration: 181.555008ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:00.365996Z","caller":"traceutil/trace.go:171","msg":"trace[344934163] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"188.501196ms","start":"2026-07-22T22:09:00.177461Z","end":"2026-07-22T22:09:00.365962Z","steps":["trace[344934163] 'process raft request' (duration: 138.48494ms)","trace[344934163] 'compare' (duration: 49.571476ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:05.957871Z","caller":"traceutil/trace.go:171","msg":"trace[1592370967] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5182; }","duration":"149.2893ms","start":"2026-07-22T22:09:05.808559Z","end":"2026-07-22T22:09:05.957848Z","steps":["trace[1592370967] 'read index received' (duration: 149.169008ms)","trace[1592370967] 'applied index is now lower than readState.Index' (duration: 119.302µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:05.958026Z","caller":"traceutil/trace.go:171","msg":"trace[353596913] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"201.892861ms","start":"2026-07-22T22:09:05.756103Z","end":"2026-07-22T22:09:05.957996Z","steps":["trace[353596913] 'process raft request' (duration: 201.610965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:05.958108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.529765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-07-22T22:09:05.958151Z","caller":"traceutil/trace.go:171","msg":"trace[1986414645] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4989; }","duration":"149.613157ms","start":"2026-07-22T22:09:05.808527Z","end":"2026-07-22T22:09:05.95814Z","steps":["trace[1986414645] 'agreement among raft nodes before linearized reading' (duration: 149.409073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:06.313281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.185816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:06.313344Z","caller":"traceutil/trace.go:171","msg":"trace[772242788] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4992; }","duration":"262.329389ms","start":"2026-07-22T22:09:06.051003Z","end":"2026-07-22T22:09:06.313332Z","steps":["trace[772242788] 'count revisions from in-memory index tree' (duration: 262.054924ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:06.736237Z","caller":"traceutil/trace.go:171","msg":"trace[114240132] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"140.34454ms","start":"2026-07-22T22:09:06.595836Z","end":"2026-07-22T22:09:06.73618Z","steps":["trace[114240132] 'process raft request' (duration: 89.601189ms)","trace[114240132] 'compare' (duration: 49.975075ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:06.742094Z","caller":"traceutil/trace.go:171","msg":"trace[297622166] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"144.993168ms","start":"2026-07-22T22:09:06.59708Z","end":"2026-07-22T22:09:06.742073Z","steps":["trace[297622166] 'process raft request' (duration: 144.57011ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:31.171159Z","caller":"traceutil/trace.go:171","msg":"trace[1659050703] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5356; }","duration":"279.660402ms","start":"2026-07-22T22:09:30.891462Z","end":"2026-07-22T22:09:31.171122Z","steps":["trace[1659050703] 'read index received' (duration: 278.694931ms)","trace[1659050703] 'applied index is now lower than readState.Index' (duration: 963.581µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:31.171218Z","caller":"traceutil/trace.go:171","msg":"trace[1473873854] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"309.328185ms","start":"2026-07-22T22:09:30.861859Z","end":"2026-07-22T22:09:31.171187Z","steps":["trace[1473873854] 'process raft request' (duration: 308.403585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:31.171375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:30.86183Z","time spent":"309.461978ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:09:31.171711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.303089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-22T22:09:31.171793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.312259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-22T22:09:31.171838Z","caller":"traceutil/trace.go:171","msg":"trace[1027393021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5158; }","duration":"275.510233ms","start":"2026-07-22T22:09:30.896295Z","end":"2026-07-22T22:09:31.171806Z","steps":["trace[1027393021] 'agreement among raft nodes before linearized reading' (duration: 275.113275ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:31.171884Z","caller":"traceutil/trace.go:171","msg":"trace[347818888] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5158; }","duration":"108.432731ms","start":"2026-07-22T22:09:31.063424Z","end":"2026-07-22T22:09:31.171857Z","steps":["trace[347818888] 'agreement among raft nodes before linearized reading' (duration: 108.106005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:31.171917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.046336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3242"} {"level":"info","ts":"2026-07-22T22:09:31.171994Z","caller":"traceutil/trace.go:171","msg":"trace[654557211] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5158; }","duration":"163.169008ms","start":"2026-07-22T22:09:31.008802Z","end":"2026-07-22T22:09:31.171971Z","steps":["trace[654557211] 'agreement among raft nodes before linearized reading' (duration: 162.939573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:31.17267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.201154ms","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-07-22T22:09:31.172738Z","caller":"traceutil/trace.go:171","msg":"trace[163273406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5158; }","duration":"281.294896ms","start":"2026-07-22T22:09:30.891425Z","end":"2026-07-22T22:09:31.17272Z","steps":["trace[163273406] 'agreement among raft nodes before linearized reading' (duration: 279.907507ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:32.175805Z","caller":"traceutil/trace.go:171","msg":"trace[2105292273] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"115.786889ms","start":"2026-07-22T22:09:32.059987Z","end":"2026-07-22T22:09:32.175773Z","steps":["trace[2105292273] 'process raft request' (duration: 115.562554ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:32.469856Z","caller":"traceutil/trace.go:171","msg":"trace[1711998934] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"408.063709ms","start":"2026-07-22T22:09:32.06176Z","end":"2026-07-22T22:09:32.469824Z","steps":["trace[1711998934] 'process raft request' (duration: 408.023268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.470029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:32.061743Z","time spent":"408.191132ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:32.470141Z","caller":"traceutil/trace.go:171","msg":"trace[1421064107] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"410.101303ms","start":"2026-07-22T22:09:32.06001Z","end":"2026-07-22T22:09:32.470112Z","steps":["trace[1421064107] 'process raft request' (duration: 409.434389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.470287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:32.059979Z","time spent":"410.220186ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:10:06.869984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-07-22T22:10:06.955142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"83.060901ms","hash":2875355459,"current-db-size-bytes":52830208,"current-db-size":"53 MB","current-db-size-in-use-bytes":28643328,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-22T22:10:06.955223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2875355459,"revision":2841,"compact-revision":-1} {"level":"warn","ts":"2026-07-22T22:11:04.861923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342224545278892,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:04.938892Z","caller":"traceutil/trace.go:171","msg":"trace[2043986585] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"911.302004ms","start":"2026-07-22T22:11:04.027564Z","end":"2026-07-22T22:11:04.938866Z","steps":["trace[2043986585] 'process raft request' (duration: 911.13ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.939138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.027538Z","time spent":"911.424117ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:04.945558Z","caller":"traceutil/trace.go:171","msg":"trace[1125793974] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"445.571552ms","start":"2026-07-22T22:11:04.499963Z","end":"2026-07-22T22:11:04.945535Z","steps":["trace[1125793974] 'process raft request' (duration: 445.349567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.945673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.49994Z","time spent":"445.673885ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:04.945794Z","caller":"traceutil/trace.go:171","msg":"trace[1440701626] linearizableReadLoop","detail":"{readStateIndex:6092; appliedIndex:6088; }","duration":"583.950266ms","start":"2026-07-22T22:11:04.361636Z","end":"2026-07-22T22:11:04.945586Z","steps":["trace[1440701626] 'read index received' (duration: 577.08417ms)","trace[1440701626] 'applied index is now lower than readState.Index' (duration: 6.864896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:04.945971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.331174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:11:04.946037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.795266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:04.946076Z","caller":"traceutil/trace.go:171","msg":"trace[841256837] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5873; }","duration":"252.917749ms","start":"2026-07-22T22:11:04.693148Z","end":"2026-07-22T22:11:04.946065Z","steps":["trace[841256837] 'agreement among raft nodes before linearized reading' (duration: 252.769476ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:04.946058Z","caller":"traceutil/trace.go:171","msg":"trace[901455095] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5873; }","duration":"584.451687ms","start":"2026-07-22T22:11:04.361588Z","end":"2026-07-22T22:11:04.946039Z","steps":["trace[901455095] 'agreement among raft nodes before linearized reading' (duration: 584.326214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.946133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.361569Z","time spent":"584.549508ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:11:04.946378Z","caller":"traceutil/trace.go:171","msg":"trace[399557251] transaction","detail":"{read_only:false; response_revision:5872; number_of_response:1; }","duration":"114.128386ms","start":"2026-07-22T22:11:04.831695Z","end":"2026-07-22T22:11:04.945823Z","steps":["trace[399557251] 'process raft request' (duration: 113.81899ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:04.946404Z","caller":"traceutil/trace.go:171","msg":"trace[1900676538] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"140.450688ms","start":"2026-07-22T22:11:04.80537Z","end":"2026-07-22T22:11:04.945821Z","steps":["trace[1900676538] 'process raft request' (duration: 140.102271ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.316324Z","caller":"traceutil/trace.go:171","msg":"trace[1287903926] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"107.725201ms","start":"2026-07-22T22:11:33.208566Z","end":"2026-07-22T22:11:33.316291Z","steps":["trace[1287903926] 'process raft request' (duration: 57.371755ms)","trace[1287903926] 'compare' (duration: 50.221523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:05.732559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.535739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-22T22:12:05.73267Z","caller":"traceutil/trace.go:171","msg":"trace[1614304581] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6210; }","duration":"226.62845ms","start":"2026-07-22T22:12:05.505984Z","end":"2026-07-22T22:12:05.732613Z","steps":["trace[1614304581] 'range keys from in-memory index tree' (duration: 226.426616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:05.732623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.773376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-22T22:12:05.732813Z","caller":"traceutil/trace.go:171","msg":"trace[821836152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6210; }","duration":"229.01387ms","start":"2026-07-22T22:12:05.503779Z","end":"2026-07-22T22:12:05.732793Z","steps":["trace[821836152] 'range keys from in-memory index tree' (duration: 228.546741ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:40.710046Z","caller":"traceutil/trace.go:171","msg":"trace[2014971450] linearizableReadLoop","detail":"{readStateIndex:6796; appliedIndex:6795; }","duration":"501.839052ms","start":"2026-07-22T22:12:40.20818Z","end":"2026-07-22T22:12:40.710019Z","steps":["trace[2014971450] 'read index received' (duration: 501.557956ms)","trace[2014971450] 'applied index is now lower than readState.Index' (duration: 279.846µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:40.710191Z","caller":"traceutil/trace.go:171","msg":"trace[1874491415] transaction","detail":"{read_only:false; response_revision:6552; number_of_response:1; }","duration":"502.291321ms","start":"2026-07-22T22:12:40.207859Z","end":"2026-07-22T22:12:40.710151Z","steps":["trace[1874491415] 'process raft request' (duration: 501.949013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.710408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.195519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-22T22:12:40.710465Z","caller":"traceutil/trace.go:171","msg":"trace[670165981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6552; }","duration":"502.299431ms","start":"2026-07-22T22:12:40.208149Z","end":"2026-07-22T22:12:40.710449Z","steps":["trace[670165981] 'agreement among raft nodes before linearized reading' (duration: 502.043485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.710524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.20814Z","time spent":"502.367113ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:12:40.710535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.824283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:12:40.710612Z","caller":"traceutil/trace.go:171","msg":"trace[538404644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6552; }","duration":"473.993447ms","start":"2026-07-22T22:12:40.236602Z","end":"2026-07-22T22:12:40.710596Z","steps":["trace[538404644] 'agreement among raft nodes before linearized reading' (duration: 473.693051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.710655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.23659Z","time spent":"474.054838ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:12:40.7109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.400736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-07-22T22:12:40.710938Z","caller":"traceutil/trace.go:171","msg":"trace[3266391] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6552; }","duration":"321.472128ms","start":"2026-07-22T22:12:40.389456Z","end":"2026-07-22T22:12:40.710928Z","steps":["trace[3266391] 'agreement among raft nodes before linearized reading' (duration: 321.369646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.710992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.389441Z","time spent":"321.521319ms","remote":"127.0.0.1:60530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4881,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-22T22:12:40.711226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.482653ms","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-07-22T22:12:40.711264Z","caller":"traceutil/trace.go:171","msg":"trace[1450081146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6552; }","duration":"375.558295ms","start":"2026-07-22T22:12:40.335696Z","end":"2026-07-22T22:12:40.711255Z","steps":["trace[1450081146] 'agreement among raft nodes before linearized reading' (duration: 375.464073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.711295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.335679Z","time spent":"375.608385ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:12:40.711334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.888299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:12:40.711583Z","caller":"traceutil/trace.go:171","msg":"trace[557678266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6552; }","duration":"470.137894ms","start":"2026-07-22T22:12:40.241427Z","end":"2026-07-22T22:12:40.711565Z","steps":["trace[557678266] 'agreement among raft nodes before linearized reading' (duration: 469.785107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.711735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.241361Z","time spent":"470.260457ms","remote":"127.0.0.1:60566","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":"warn","ts":"2026-07-22T22:12:40.712046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.488596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:12:40.712068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.871803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-07-22T22:12:40.712104Z","caller":"traceutil/trace.go:171","msg":"trace[1535795301] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:6552; }","duration":"187.591228ms","start":"2026-07-22T22:12:40.524499Z","end":"2026-07-22T22:12:40.71209Z","steps":["trace[1535795301] 'agreement among raft nodes before linearized reading' (duration: 187.492946ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:40.712117Z","caller":"traceutil/trace.go:171","msg":"trace[981711408] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6552; }","duration":"269.08141ms","start":"2026-07-22T22:12:40.443022Z","end":"2026-07-22T22:12:40.712104Z","steps":["trace[981711408] 'agreement among raft nodes before linearized reading' (duration: 267.73262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.710336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:40.207841Z","time spent":"502.418274ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:13:14.497935Z","caller":"traceutil/trace.go:171","msg":"trace[1421382616] transaction","detail":"{read_only:false; response_revision:6783; number_of_response:1; }","duration":"233.533853ms","start":"2026-07-22T22:13:14.264179Z","end":"2026-07-22T22:13:14.497713Z","steps":["trace[1421382616] 'process raft request' (duration: 233.371408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.2167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342224545281368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:13:18.356477Z","caller":"traceutil/trace.go:171","msg":"trace[1729071925] linearizableReadLoop","detail":"{readStateIndex:7055; appliedIndex:7053; }","duration":"640.257ms","start":"2026-07-22T22:13:17.716196Z","end":"2026-07-22T22:13:18.356453Z","steps":["trace[1729071925] 'read index received' (duration: 634.227878ms)","trace[1729071925] 'applied index is now lower than readState.Index' (duration: 6.028092ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:13:18.356715Z","caller":"traceutil/trace.go:171","msg":"trace[96093637] transaction","detail":"{read_only:false; response_revision:6799; number_of_response:1; }","duration":"641.817697ms","start":"2026-07-22T22:13:17.714888Z","end":"2026-07-22T22:13:18.356706Z","steps":["trace[96093637] 'process raft request' (duration: 635.523967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.356827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:17.714856Z","time spent":"641.883769ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:13:18.35703Z","caller":"traceutil/trace.go:171","msg":"trace[1485738114] transaction","detail":"{read_only:false; response_revision:6800; number_of_response:1; }","duration":"607.128018ms","start":"2026-07-22T22:13:17.749895Z","end":"2026-07-22T22:13:18.357023Z","steps":["trace[1485738114] 'process raft request' (duration: 606.503859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.357062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:17.749879Z","time spent":"607.166809ms","remote":"127.0.0.1:60396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:13:18.357247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.061704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-22T22:13:18.357271Z","caller":"traceutil/trace.go:171","msg":"trace[1991880719] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6800; }","duration":"641.087294ms","start":"2026-07-22T22:13:17.716179Z","end":"2026-07-22T22:13:18.357266Z","steps":["trace[1991880719] 'agreement among raft nodes before linearized reading' (duration: 640.975321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.357287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:17.716157Z","time spent":"641.126686ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-22T22:13:18.357429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.375729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:13:18.357448Z","caller":"traceutil/trace.go:171","msg":"trace[73928265] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:6800; }","duration":"455.461942ms","start":"2026-07-22T22:13:17.901981Z","end":"2026-07-22T22:13:18.357443Z","steps":["trace[73928265] 'agreement among raft nodes before linearized reading' (duration: 455.422811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.357462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:17.901948Z","time spent":"455.510433ms","remote":"127.0.0.1:60458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:13:18.357681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.067586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:13:18.357702Z","caller":"traceutil/trace.go:171","msg":"trace[162367161] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6800; }","duration":"163.090116ms","start":"2026-07-22T22:13:18.194607Z","end":"2026-07-22T22:13:18.357697Z","steps":["trace[162367161] 'agreement among raft nodes before linearized reading' (duration: 163.054455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.357807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.398825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:13:18.357826Z","caller":"traceutil/trace.go:171","msg":"trace[1084513871] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6800; }","duration":"394.456247ms","start":"2026-07-22T22:13:17.963365Z","end":"2026-07-22T22:13:18.357821Z","steps":["trace[1084513871] 'agreement among raft nodes before linearized reading' (duration: 394.425476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.357844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:17.963341Z","time spent":"394.498609ms","remote":"127.0.0.1:58842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:13:18.917224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.594865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:13:18.917621Z","caller":"traceutil/trace.go:171","msg":"trace[2117891186] linearizableReadLoop","detail":"{readStateIndex:7060; appliedIndex:7059; }","duration":"250.59351ms","start":"2026-07-22T22:13:18.666759Z","end":"2026-07-22T22:13:18.917352Z","steps":["trace[2117891186] 'read index received' (duration: 75.663626ms)","trace[2117891186] 'applied index is now lower than readState.Index' (duration: 174.928104ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:13:18.917766Z","caller":"traceutil/trace.go:171","msg":"trace[1547019795] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"402.87405ms","start":"2026-07-22T22:13:18.514875Z","end":"2026-07-22T22:13:18.917749Z","steps":["trace[1547019795] 'process raft request' (duration: 227.617206ms)","trace[1547019795] 'compare' (duration: 174.171012ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:13:18.917845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:13:18.514847Z","time spent":"402.955713ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:13:18.918269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.513988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:13:18.91832Z","caller":"traceutil/trace.go:171","msg":"trace[643491163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6805; }","duration":"251.605271ms","start":"2026-07-22T22:13:18.6667Z","end":"2026-07-22T22:13:18.918305Z","steps":["trace[643491163] 'agreement among raft nodes before linearized reading' (duration: 251.440366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.918536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.327635ms","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-07-22T22:13:18.918578Z","caller":"traceutil/trace.go:171","msg":"trace[1969968658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:6805; }","duration":"221.394087ms","start":"2026-07-22T22:13:18.697173Z","end":"2026-07-22T22:13:18.918567Z","steps":["trace[1969968658] 'agreement among raft nodes before linearized reading' (duration: 221.331336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:18.919024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.864062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:437802"} {"level":"info","ts":"2026-07-22T22:13:18.919103Z","caller":"traceutil/trace.go:171","msg":"trace[1420932101] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6805; }","duration":"180.969646ms","start":"2026-07-22T22:13:18.738119Z","end":"2026-07-22T22:13:18.919089Z","steps":["trace[1420932101] 'agreement among raft nodes before linearized reading' (duration: 180.584705ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:06.875639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5469} {"level":"info","ts":"2026-07-22T22:15:06.950655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5469,"took":"73.371228ms","hash":2934296895,"current-db-size-bytes":52830208,"current-db-size":"53 MB","current-db-size-in-use-bytes":18464768,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-22T22:15:06.950721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2934296895,"revision":5469,"compact-revision":2841}