{"level":"warn","ts":"2026-05-18T00:35:54.71262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:35:54.712732Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-18T00:35:54.712837Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:35:54.712853Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-18T00:35:54.712881Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-18T00:35:54.713688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-18T00:35:54.713784Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-18T00:35:54.718773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.753508ms"} {"level":"info","ts":"2026-05-18T00:35:54.727108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-18T00:35:54.727293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T00:35:54.727385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-18T00:35:54.72742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T00:35:54.72747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-18T00:35:54.727575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-18T00:35:54.734885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T00:35:54.736264Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T00:35:54.737499Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-18T00:35:54.739624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T00:35:54.740149Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T00:35:54.74142Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:35:54.741539Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:35:54.741556Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:35:54.746601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-18T00:35:54.746814Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-18T00:35:54.748379Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-18T00:35:54.748746Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T00:35:54.748803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T00:35:54.749043Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-18T00:35:54.749088Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-18T00:35:55.228169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T00:35:55.228254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T00:35:55.228354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-18T00:35:55.228417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-18T00:35:55.228438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-18T00:35:55.228457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-18T00:35:55.22853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-18T00:35:55.238187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:35:55.23889Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T00:35:55.239188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:35:55.239295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:35:55.243124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-18T00:35:55.243352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T00:35:55.243368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T00:35:55.247779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T00:35:55.257846Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:35:55.257988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:35:55.258071Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:36:06.878231Z","caller":"traceutil/trace.go:171","msg":"trace[1955818456] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"265.021717ms","start":"2026-05-18T00:36:06.613167Z","end":"2026-05-18T00:36:06.878189Z","steps":["trace[1955818456] 'process raft request' (duration: 257.061594ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:06.878415Z","caller":"traceutil/trace.go:171","msg":"trace[94527631] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"163.42729ms","start":"2026-05-18T00:36:06.71494Z","end":"2026-05-18T00:36:06.878367Z","steps":["trace[94527631] 'read index received' (duration: 155.279113ms)","trace[94527631] 'applied index is now lower than readState.Index' (duration: 8.144247ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:36:06.878487Z","caller":"traceutil/trace.go:171","msg":"trace[1984817041] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"263.32555ms","start":"2026-05-18T00:36:06.615136Z","end":"2026-05-18T00:36:06.878462Z","steps":["trace[1984817041] 'process raft request' (duration: 262.969451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:06.878626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.651686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:06.878729Z","caller":"traceutil/trace.go:171","msg":"trace[1885036941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"163.833721ms","start":"2026-05-18T00:36:06.714881Z","end":"2026-05-18T00:36:06.878715Z","steps":["trace[1885036941] 'agreement among raft nodes before linearized reading' (duration: 163.654747ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:07.158004Z","caller":"traceutil/trace.go:171","msg":"trace[1810867317] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"270.048259ms","start":"2026-05-18T00:36:06.887924Z","end":"2026-05-18T00:36:07.157973Z","steps":["trace[1810867317] 'process raft request' (duration: 269.851455ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:33.52159Z","caller":"traceutil/trace.go:171","msg":"trace[376208035] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"193.728746ms","start":"2026-05-18T00:36:33.327824Z","end":"2026-05-18T00:36:33.521553Z","steps":["trace[376208035] 'process raft request' (duration: 192.959605ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:33.707778Z","caller":"traceutil/trace.go:171","msg":"trace[999972998] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"109.153261ms","start":"2026-05-18T00:36:33.598586Z","end":"2026-05-18T00:36:33.707739Z","steps":["trace[999972998] 'process raft request' (duration: 104.693535ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:33.712504Z","caller":"traceutil/trace.go:171","msg":"trace[1832038385] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"105.693721ms","start":"2026-05-18T00:36:33.606776Z","end":"2026-05-18T00:36:33.71247Z","steps":["trace[1832038385] 'read index received' (duration: 96.618545ms)","trace[1832038385] 'applied index is now lower than readState.Index' (duration: 9.073756ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:36:33.71278Z","caller":"traceutil/trace.go:171","msg":"trace[978380996] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"112.537303ms","start":"2026-05-18T00:36:33.600225Z","end":"2026-05-18T00:36:33.712762Z","steps":["trace[978380996] 'process raft request' (duration: 112.061445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:33.712965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.136637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-krgmz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-18T00:36:33.713014Z","caller":"traceutil/trace.go:171","msg":"trace[1274742352] range","detail":"{range_begin:/registry/pods/kube-system/cilium-krgmz; range_end:; response_count:1; response_revision:621; }","duration":"106.259999ms","start":"2026-05-18T00:36:33.606741Z","end":"2026-05-18T00:36:33.713001Z","steps":["trace[1274742352] 'agreement among raft nodes before linearized reading' (duration: 105.860034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.288264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.645663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-18T00:36:34.288425Z","caller":"traceutil/trace.go:171","msg":"trace[845976370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"469.845806ms","start":"2026-05-18T00:36:33.818561Z","end":"2026-05-18T00:36:34.288407Z","steps":["trace[845976370] 'range keys from in-memory index tree' (duration: 469.515712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.288496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:36:33.818547Z","time spent":"469.936478ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T00:36:34.288924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.962178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-18T00:36:34.289047Z","caller":"traceutil/trace.go:171","msg":"trace[1322136287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"249.161912ms","start":"2026-05-18T00:36:34.039858Z","end":"2026-05-18T00:36:34.28902Z","steps":["trace[1322136287] 'range keys from in-memory index tree' (duration: 248.693275ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:34.328972Z","caller":"traceutil/trace.go:171","msg":"trace[1111877115] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"217.665267ms","start":"2026-05-18T00:36:34.11129Z","end":"2026-05-18T00:36:34.328956Z","steps":["trace[1111877115] 'process raft request' (duration: 209.718877ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:34.328905Z","caller":"traceutil/trace.go:171","msg":"trace[459417430] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"201.478162ms","start":"2026-05-18T00:36:34.127336Z","end":"2026-05-18T00:36:34.328814Z","steps":["trace[459417430] 'read index received' (duration: 192.13006ms)","trace[459417430] 'applied index is now lower than readState.Index' (duration: 9.333821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:36:34.34823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.292246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:36:34.348312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.490549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.348322Z","caller":"traceutil/trace.go:171","msg":"trace[902931294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:625; }","duration":"116.416428ms","start":"2026-05-18T00:36:34.231882Z","end":"2026-05-18T00:36:34.348298Z","steps":["trace[902931294] 'agreement among raft nodes before linearized reading' (duration: 116.243706ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:34.348342Z","caller":"traceutil/trace.go:171","msg":"trace[653448672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:625; }","duration":"116.53315ms","start":"2026-05-18T00:36:34.231798Z","end":"2026-05-18T00:36:34.348331Z","steps":["trace[653448672] 'agreement among raft nodes before linearized reading' (duration: 116.49219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.348546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.216391ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.348582Z","caller":"traceutil/trace.go:171","msg":"trace[1877798741] 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:625; }","duration":"221.249651ms","start":"2026-05-18T00:36:34.127318Z","end":"2026-05-18T00:36:34.348568Z","steps":["trace[1877798741] 'agreement among raft nodes before linearized reading' (duration: 201.608414ms)","trace[1877798741] 'range keys from in-memory index tree' (duration: 19.589445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:36:34.348647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.47172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.348745Z","caller":"traceutil/trace.go:171","msg":"trace[1418271313] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:625; }","duration":"175.566971ms","start":"2026-05-18T00:36:34.173133Z","end":"2026-05-18T00:36:34.3487Z","steps":["trace[1418271313] 'agreement among raft nodes before linearized reading' (duration: 175.47173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.3489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.894535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.348929Z","caller":"traceutil/trace.go:171","msg":"trace[1698832934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:625; }","duration":"116.975827ms","start":"2026-05-18T00:36:34.231946Z","end":"2026-05-18T00:36:34.348922Z","steps":["trace[1698832934] 'agreement among raft nodes before linearized reading' (duration: 116.929887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.348257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.1631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.348995Z","caller":"traceutil/trace.go:171","msg":"trace[1440159693] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:625; }","duration":"176.972243ms","start":"2026-05-18T00:36:34.172013Z","end":"2026-05-18T00:36:34.348985Z","steps":["trace[1440159693] 'agreement among raft nodes before linearized reading' (duration: 176.13083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.349019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.165803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:36:34.349572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.958692ms","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-05-18T00:36:34.349608Z","caller":"traceutil/trace.go:171","msg":"trace[470472214] 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:625; }","duration":"222.059704ms","start":"2026-05-18T00:36:34.127539Z","end":"2026-05-18T00:36:34.349599Z","steps":["trace[470472214] 'agreement among raft nodes before linearized reading' (duration: 222.006033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.34967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.666453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.349665Z","caller":"traceutil/trace.go:171","msg":"trace[1122798495] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:625; }","duration":"143.412985ms","start":"2026-05-18T00:36:34.205793Z","end":"2026-05-18T00:36:34.349206Z","steps":["trace[1122798495] 'agreement among raft nodes before linearized reading' (duration: 142.836797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.349712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.267912ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.34981Z","caller":"traceutil/trace.go:171","msg":"trace[982026005] 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:625; }","duration":"221.371713ms","start":"2026-05-18T00:36:34.128428Z","end":"2026-05-18T00:36:34.349799Z","steps":["trace[982026005] 'agreement among raft nodes before linearized reading' (duration: 221.255612ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:36:34.349711Z","caller":"traceutil/trace.go:171","msg":"trace[1675123763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:625; }","duration":"177.727314ms","start":"2026-05-18T00:36:34.171974Z","end":"2026-05-18T00:36:34.349701Z","steps":["trace[1675123763] 'agreement among raft nodes before linearized reading' (duration: 177.661633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:36:34.350321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.589235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:36:34.350417Z","caller":"traceutil/trace.go:171","msg":"trace[420632494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:625; }","duration":"144.733345ms","start":"2026-05-18T00:36:34.205662Z","end":"2026-05-18T00:36:34.350395Z","steps":["trace[420632494] 'agreement among raft nodes before linearized reading' (duration: 142.911037ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:10.264598Z","caller":"traceutil/trace.go:171","msg":"trace[978603822] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"191.448308ms","start":"2026-05-18T00:38:10.073089Z","end":"2026-05-18T00:38:10.264538Z","steps":["trace[978603822] 'process raft request' (duration: 191.245114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:10.264922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.634402ms","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-05-18T00:38:10.265005Z","caller":"traceutil/trace.go:171","msg":"trace[260096464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"140.852676ms","start":"2026-05-18T00:38:10.124141Z","end":"2026-05-18T00:38:10.264993Z","steps":["trace[260096464] 'agreement among raft nodes before linearized reading' (duration: 140.565429ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:10.264578Z","caller":"traceutil/trace.go:171","msg":"trace[1466317993] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1942; }","duration":"140.266373ms","start":"2026-05-18T00:38:10.124272Z","end":"2026-05-18T00:38:10.264538Z","steps":["trace[1466317993] 'read index received' (duration: 140.253793ms)","trace[1466317993] 'applied index is now lower than readState.Index' (duration: 9.85µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:38:10.289324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.328933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-18T00:38:10.289419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.885158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-05-18T00:38:10.289442Z","caller":"traceutil/trace.go:171","msg":"trace[631768350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"120.508377ms","start":"2026-05-18T00:38:10.168907Z","end":"2026-05-18T00:38:10.289415Z","steps":["trace[631768350] 'agreement among raft nodes before linearized reading' (duration: 120.221341ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:10.28949Z","caller":"traceutil/trace.go:171","msg":"trace[1585192369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1867; }","duration":"152.98531ms","start":"2026-05-18T00:38:10.136485Z","end":"2026-05-18T00:38:10.289471Z","steps":["trace[1585192369] 'agreement among raft nodes before linearized reading' (duration: 152.798645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:10.289356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.467241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8912132-6a4a-4231-9ee6-14d8ca4d9993\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-18T00:38:10.28969Z","caller":"traceutil/trace.go:171","msg":"trace[949278548] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8912132-6a4a-4231-9ee6-14d8ca4d9993; range_end:; response_count:1; response_revision:1867; }","duration":"110.722117ms","start":"2026-05-18T00:38:10.178814Z","end":"2026-05-18T00:38:10.289536Z","steps":["trace[949278548] 'agreement among raft nodes before linearized reading' (duration: 110.277547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:10.654164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.013116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:38:10.654325Z","caller":"traceutil/trace.go:171","msg":"trace[164945340] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"365.280007ms","start":"2026-05-18T00:38:10.289021Z","end":"2026-05-18T00:38:10.654301Z","steps":["trace[164945340] 'read index received' (duration: 40.226317ms)","trace[164945340] 'applied index is now lower than readState.Index' (duration: 325.05184ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:38:10.654592Z","caller":"traceutil/trace.go:171","msg":"trace[333856726] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"385.144195ms","start":"2026-05-18T00:38:10.269412Z","end":"2026-05-18T00:38:10.654556Z","steps":["trace[333856726] 'process raft request' (duration: 59.821489ms)","trace[333856726] 'compare' (duration: 323.850342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:38:10.654813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:38:10.269389Z","time spent":"385.303219ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:38:10.654852Z","caller":"traceutil/trace.go:171","msg":"trace[459196788] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"351.424463ms","start":"2026-05-18T00:38:10.303394Z","end":"2026-05-18T00:38:10.654818Z","steps":["trace[459196788] 'process raft request' (duration: 351.003074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:10.654869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.070574ms","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-05-18T00:38:10.655172Z","caller":"traceutil/trace.go:171","msg":"trace[179295697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1868; }","duration":"389.519063ms","start":"2026-05-18T00:38:10.265636Z","end":"2026-05-18T00:38:10.655155Z","steps":["trace[179295697] 'agreement among raft nodes before linearized reading' (duration: 388.727736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:10.655325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:38:10.265557Z","time spent":"389.660827ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T00:38:10.655026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:38:10.303373Z","time spent":"351.558817ms","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:38:10.685464Z","caller":"traceutil/trace.go:171","msg":"trace[1955075788] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"261.2199ms","start":"2026-05-18T00:38:10.424215Z","end":"2026-05-18T00:38:10.685435Z","steps":["trace[1955075788] 'process raft request' (duration: 261.043296ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:23.594087Z","caller":"traceutil/trace.go:171","msg":"trace[4183682] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2056; }","duration":"158.64174ms","start":"2026-05-18T00:38:23.435419Z","end":"2026-05-18T00:38:23.59406Z","steps":["trace[4183682] 'read index received' (duration: 158.468766ms)","trace[4183682] 'applied index is now lower than readState.Index' (duration: 171.744µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:38:23.594209Z","caller":"traceutil/trace.go:171","msg":"trace[478306328] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"189.534398ms","start":"2026-05-18T00:38:23.40465Z","end":"2026-05-18T00:38:23.594184Z","steps":["trace[478306328] 'process raft request' (duration: 189.250681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:23.594339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.904805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-05-18T00:38:23.594377Z","caller":"traceutil/trace.go:171","msg":"trace[441683705] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1978; }","duration":"159.005348ms","start":"2026-05-18T00:38:23.435362Z","end":"2026-05-18T00:38:23.594367Z","steps":["trace[441683705] 'agreement among raft nodes before linearized reading' (duration: 158.838274ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:23.594748Z","caller":"traceutil/trace.go:171","msg":"trace[1479905104] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"116.585814ms","start":"2026-05-18T00:38:23.478141Z","end":"2026-05-18T00:38:23.594726Z","steps":["trace[1479905104] 'process raft request' (duration: 116.373039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:45.126652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.593761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:38:45.126838Z","caller":"traceutil/trace.go:171","msg":"trace[1424318925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2114; }","duration":"101.881888ms","start":"2026-05-18T00:38:45.024933Z","end":"2026-05-18T00:38:45.126815Z","steps":["trace[1424318925] 'range keys from in-memory index tree' (duration: 101.371237ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:45.607295Z","caller":"traceutil/trace.go:171","msg":"trace[354483872] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"142.130213ms","start":"2026-05-18T00:38:45.465121Z","end":"2026-05-18T00:38:45.607251Z","steps":["trace[354483872] 'process raft request' (duration: 141.755284ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:45.84698Z","caller":"traceutil/trace.go:171","msg":"trace[848366263] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"169.304148ms","start":"2026-05-18T00:38:45.677637Z","end":"2026-05-18T00:38:45.846941Z","steps":["trace[848366263] 'process raft request' (duration: 119.037029ms)","trace[848366263] 'compare' (duration: 50.102085ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:38:45.857776Z","caller":"traceutil/trace.go:171","msg":"trace[103916923] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"143.430942ms","start":"2026-05-18T00:38:45.714325Z","end":"2026-05-18T00:38:45.857756Z","steps":["trace[103916923] 'read index received' (duration: 82.532505ms)","trace[103916923] 'applied index is now lower than readState.Index' (duration: 60.896847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:38:45.858055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.910269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:38:45.857901Z","caller":"traceutil/trace.go:171","msg":"trace[1290497511] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"177.716016ms","start":"2026-05-18T00:38:45.680126Z","end":"2026-05-18T00:38:45.857842Z","steps":["trace[1290497511] 'process raft request' (duration: 177.538302ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:38:45.858198Z","caller":"traceutil/trace.go:171","msg":"trace[1951546244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2119; }","duration":"143.136175ms","start":"2026-05-18T00:38:45.715028Z","end":"2026-05-18T00:38:45.858164Z","steps":["trace[1951546244] 'agreement among raft nodes before linearized reading' (duration: 142.879749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:38:45.859797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.437569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:38:45.860531Z","caller":"traceutil/trace.go:171","msg":"trace[4101340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2119; }","duration":"145.639404ms","start":"2026-05-18T00:38:45.714228Z","end":"2026-05-18T00:38:45.859867Z","steps":["trace[4101340] 'agreement among raft nodes before linearized reading' (duration: 143.652617ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:39:57.608512Z","caller":"traceutil/trace.go:171","msg":"trace[592089812] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"205.277272ms","start":"2026-05-18T00:39:57.403222Z","end":"2026-05-18T00:39:57.608499Z","steps":["trace[592089812] 'process raft request' (duration: 205.18471ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:39:57.625596Z","caller":"traceutil/trace.go:171","msg":"trace[1271672655] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2699; }","duration":"111.083606ms","start":"2026-05-18T00:39:57.514503Z","end":"2026-05-18T00:39:57.625586Z","steps":["trace[1271672655] 'read index received' (duration: 93.91442ms)","trace[1271672655] 'applied index is now lower than readState.Index' (duration: 17.168436ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:39:57.625763Z","caller":"traceutil/trace.go:171","msg":"trace[1013492571] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"221.824543ms","start":"2026-05-18T00:39:57.403931Z","end":"2026-05-18T00:39:57.625756Z","steps":["trace[1013492571] 'process raft request' (duration: 220.279586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:57.625998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.477086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-18T00:39:57.626029Z","caller":"traceutil/trace.go:171","msg":"trace[1513912812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2601; }","duration":"111.545668ms","start":"2026-05-18T00:39:57.514476Z","end":"2026-05-18T00:39:57.626022Z","steps":["trace[1513912812] 'agreement among raft nodes before linearized reading' (duration: 111.422735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.763697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.796335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:40:30.763938Z","caller":"traceutil/trace.go:171","msg":"trace[1053760471] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"978.589444ms","start":"2026-05-18T00:40:29.785303Z","end":"2026-05-18T00:40:30.763892Z","steps":["trace[1053760471] 'process raft request' (duration: 192.503742ms)","trace[1053760471] 'compare' (duration: 785.442805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:40:30.764154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:29.785279Z","time spent":"978.751317ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:40:30.765072Z","caller":"traceutil/trace.go:171","msg":"trace[67309235] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"978.916362ms","start":"2026-05-18T00:40:29.786129Z","end":"2026-05-18T00:40:30.765045Z","steps":["trace[67309235] 'process raft request' (duration: 977.687632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.765163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:29.786119Z","time spent":"978.999164ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:40:30.765223Z","caller":"traceutil/trace.go:171","msg":"trace[1197125353] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2885; }","duration":"979.064946ms","start":"2026-05-18T00:40:29.786082Z","end":"2026-05-18T00:40:30.765147Z","steps":["trace[1197125353] 'read index received' (duration: 28.833895ms)","trace[1197125353] 'applied index is now lower than readState.Index' (duration: 950.228711ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:30.765311Z","caller":"traceutil/trace.go:171","msg":"trace[1930942542] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"483.563463ms","start":"2026-05-18T00:40:30.281699Z","end":"2026-05-18T00:40:30.765262Z","steps":["trace[1930942542] 'process raft request' (duration: 483.334397ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.765377Z","caller":"traceutil/trace.go:171","msg":"trace[805950247] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"481.271327ms","start":"2026-05-18T00:40:30.284068Z","end":"2026-05-18T00:40:30.765339Z","steps":["trace[805950247] 'process raft request' (duration: 481.152744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.765522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.431515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-18T00:40:30.76587Z","caller":"traceutil/trace.go:171","msg":"trace[20567055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2782; }","duration":"979.790833ms","start":"2026-05-18T00:40:29.786065Z","end":"2026-05-18T00:40:30.765856Z","steps":["trace[20567055] 'agreement among raft nodes before linearized reading' (duration: 979.266341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.765527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:30.281662Z","time spent":"483.738847ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:40:30.765948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:29.786055Z","time spent":"979.883296ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T00:40:30.765571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:30.284029Z","time spent":"481.41988ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:40:30.765635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.454009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-18T00:40:30.76617Z","caller":"traceutil/trace.go:171","msg":"trace[1674117140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2782; }","duration":"285.038134ms","start":"2026-05-18T00:40:30.481115Z","end":"2026-05-18T00:40:30.766153Z","steps":["trace[1674117140] 'agreement among raft nodes before linearized reading' (duration: 284.277765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.765771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.291019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b08204f30f233a\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-18T00:40:30.766247Z","caller":"traceutil/trace.go:171","msg":"trace[436941400] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b08204f30f233a; range_end:; response_count:1; response_revision:2782; }","duration":"183.799212ms","start":"2026-05-18T00:40:30.582436Z","end":"2026-05-18T00:40:30.766235Z","steps":["trace[436941400] 'agreement among raft nodes before linearized reading' (duration: 183.219827ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.912843Z","caller":"traceutil/trace.go:171","msg":"trace[951624232] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"141.888955ms","start":"2026-05-18T00:40:30.770891Z","end":"2026-05-18T00:40:30.91278Z","steps":["trace[951624232] 'process raft request' (duration: 137.255892ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.913295Z","caller":"traceutil/trace.go:171","msg":"trace[782639055] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"142.176142ms","start":"2026-05-18T00:40:30.771061Z","end":"2026-05-18T00:40:30.913238Z","steps":["trace[782639055] 'process raft request' (duration: 141.67219ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.913478Z","caller":"traceutil/trace.go:171","msg":"trace[1796529823] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"140.186874ms","start":"2026-05-18T00:40:30.773253Z","end":"2026-05-18T00:40:30.91344Z","steps":["trace[1796529823] 'process raft request' (duration: 139.950948ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:28.091502Z","caller":"traceutil/trace.go:171","msg":"trace[1629244723] transaction","detail":"{read_only:false; response_revision:3806; number_of_response:1; }","duration":"619.362744ms","start":"2026-05-18T00:43:27.472111Z","end":"2026-05-18T00:43:28.091474Z","steps":["trace[1629244723] 'process raft request' (duration: 619.169449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.091706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.472093Z","time spent":"619.525028ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:43:28.093983Z","caller":"traceutil/trace.go:171","msg":"trace[496522409] transaction","detail":"{read_only:false; response_revision:3809; number_of_response:1; }","duration":"451.012388ms","start":"2026-05-18T00:43:27.642956Z","end":"2026-05-18T00:43:28.093968Z","steps":["trace[496522409] 'process raft request' (duration: 450.942486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.094049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.642917Z","time spent":"451.103059ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:43:28.094008Z","caller":"traceutil/trace.go:171","msg":"trace[1100352572] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"619.992228ms","start":"2026-05-18T00:43:27.473966Z","end":"2026-05-18T00:43:28.093959Z","steps":["trace[1100352572] 'process raft request' (duration: 619.827425ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:28.09416Z","caller":"traceutil/trace.go:171","msg":"trace[793481759] linearizableReadLoop","detail":"{readStateIndex:3958; appliedIndex:3953; }","duration":"319.159913ms","start":"2026-05-18T00:43:27.774975Z","end":"2026-05-18T00:43:28.094135Z","steps":["trace[793481759] 'read index received' (duration: 316.296059ms)","trace[793481759] 'applied index is now lower than readState.Index' (duration: 2.862294ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:28.094287Z","caller":"traceutil/trace.go:171","msg":"trace[2068580166] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"448.964331ms","start":"2026-05-18T00:43:27.645294Z","end":"2026-05-18T00:43:28.094258Z","steps":["trace[2068580166] 'process raft request' (duration: 448.636425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.094375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.293009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-18T00:43:28.094435Z","caller":"traceutil/trace.go:171","msg":"trace[766696818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3810; }","duration":"258.387252ms","start":"2026-05-18T00:43:27.83602Z","end":"2026-05-18T00:43:28.094407Z","steps":["trace[766696818] 'agreement among raft nodes before linearized reading' (duration: 258.247239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.094434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.475301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-18T00:43:28.094563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.64528Z","time spent":"449.068414ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:43:28.094548Z","caller":"traceutil/trace.go:171","msg":"trace[129157559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3810; }","duration":"257.666006ms","start":"2026-05-18T00:43:27.836864Z","end":"2026-05-18T00:43:28.09453Z","steps":["trace[129157559] 'agreement among raft nodes before linearized reading' (duration: 257.42906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.095218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.473935Z","time spent":"620.248023ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:28.096003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.033745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-18T00:43:28.096038Z","caller":"traceutil/trace.go:171","msg":"trace[1836749001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3810; }","duration":"321.069196ms","start":"2026-05-18T00:43:27.77496Z","end":"2026-05-18T00:43:28.09603Z","steps":["trace[1836749001] 'agreement among raft nodes before linearized reading' (duration: 320.966104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.09606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.774893Z","time spent":"321.161298ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T00:43:28.097034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.203242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-18T00:43:28.09715Z","caller":"traceutil/trace.go:171","msg":"trace[1029750889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3810; }","duration":"260.337994ms","start":"2026-05-18T00:43:27.836786Z","end":"2026-05-18T00:43:28.097124Z","steps":["trace[1029750889] 'agreement among raft nodes before linearized reading' (duration: 257.720486ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:28.094057Z","caller":"traceutil/trace.go:171","msg":"trace[966816347] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"492.894639ms","start":"2026-05-18T00:43:27.601119Z","end":"2026-05-18T00:43:28.094014Z","steps":["trace[966816347] 'process raft request' (duration: 492.747086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:28.097858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:27.601103Z","time spent":"496.638174ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:33.682558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278887772784984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:43:34.183401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278887772784984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:43:34.683876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278887772784984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:43:34.829046Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.953106479s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:43:34.82962Z","caller":"traceutil/trace.go:171","msg":"trace[1621316050] transaction","detail":"{read_only:false; response_revision:3866; number_of_response:1; }","duration":"1.953774203s","start":"2026-05-18T00:43:32.875816Z","end":"2026-05-18T00:43:34.82959Z","steps":["trace[1621316050] 'process raft request' (duration: 1.953580499s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:34.829808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:32.875789Z","time spent":"1.953914137s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:35.714223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000240572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-18T00:43:35.714337Z","caller":"traceutil/trace.go:171","msg":"trace[948264235] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000424656s","start":"2026-05-18T00:43:33.713884Z","end":"2026-05-18T00:43:35.714309Z","steps":["trace[948264235] 'agreement among raft nodes before linearized reading' (duration: 2.000274993s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:35.714405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:33.713866Z","time spent":"2.000527159s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/18 00:43:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:37.59623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000229468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-18T00:43:37.596325Z","caller":"traceutil/trace.go:171","msg":"trace[1941140363] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000395112s","start":"2026-05-18T00:43:35.595906Z","end":"2026-05-18T00:43:37.596301Z","steps":["trace[1941140363] 'agreement among raft nodes before linearized reading' (duration: 2.000265739s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:37.596571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:35.595885Z","time spent":"2.000670207s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/18 00:43:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:37.714746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000038267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-18T00:43:37.714846Z","caller":"traceutil/trace.go:171","msg":"trace[404933359] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00018787s","start":"2026-05-18T00:43:35.714634Z","end":"2026-05-18T00:43:37.714821Z","steps":["trace[404933359] 'agreement among raft nodes before linearized reading' (duration: 2.000053477s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:37.715388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:35.714622Z","time spent":"2.000749442s","remote":"127.0.0.1:57456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/18 00:43:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.112785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.115068Z","time spent":"4.997708351s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.11712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.118906Z","time spent":"4.998209152s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.118796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.120456Z","time spent":"4.998332226s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.118896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.120379Z","time spent":"4.99850669s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.121788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999633205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-18T00:43:39.121857Z","caller":"traceutil/trace.go:171","msg":"trace[735229143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999777779s","start":"2026-05-18T00:43:34.122061Z","end":"2026-05-18T00:43:39.121839Z","steps":["trace[735229143] 'agreement among raft nodes before linearized reading' (duration: 4.999664506s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:39.121905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.122048Z","time spent":"4.999844s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.124789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998616982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-18T00:43:39.124876Z","caller":"traceutil/trace.go:171","msg":"trace[2134595806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998716605s","start":"2026-05-18T00:43:34.126148Z","end":"2026-05-18T00:43:39.124865Z","steps":["trace[2134595806] 'agreement among raft nodes before linearized reading' (duration: 4.998628523s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:39.124933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.126142Z","time spent":"4.998759496s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/05/18 00:43:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:43:39.557758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.709685014s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:43:39.558489Z","caller":"traceutil/trace.go:171","msg":"trace[2088280630] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"5.946346537s","start":"2026-05-18T00:43:33.612109Z","end":"2026-05-18T00:43:39.558456Z","steps":["trace[2088280630] 'process raft request' (duration: 5.945973919s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:39.558699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:33.612073Z","time spent":"5.946495951s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:39.720431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000346562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-18T00:43:39.720576Z","caller":"traceutil/trace.go:171","msg":"trace[981108286] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000551316s","start":"2026-05-18T00:43:37.720005Z","end":"2026-05-18T00:43:39.720556Z","steps":["trace[981108286] 'agreement among raft nodes before linearized reading' (duration: 2.000374573s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:39.720675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:37.719977Z","time spent":"2.00068415s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T00:43:40.850795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29234125s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-18T00:43:40.850989Z","caller":"traceutil/trace.go:171","msg":"trace[1520984571] linearizableReadLoop","detail":"{readStateIndex:4025; appliedIndex:4018; }","duration":"7.669355113s","start":"2026-05-18T00:43:33.181615Z","end":"2026-05-18T00:43:40.85097Z","steps":["trace[1520984571] 'read index received' (duration: 1.647691581s)","trace[1520984571] 'applied index is now lower than readState.Index' (duration: 6.021661482s)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:43:40.851582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.669949087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.852533Z","caller":"traceutil/trace.go:171","msg":"trace[65517702] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3871; }","duration":"7.670924368s","start":"2026-05-18T00:43:33.181571Z","end":"2026-05-18T00:43:40.852496Z","steps":["trace[65517702] 'agreement among raft nodes before linearized reading' (duration: 7.669923866s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.853485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:33.181548Z","time spent":"7.671648014s","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":16,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.871971Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.313866403s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:43:40.87244Z","caller":"traceutil/trace.go:171","msg":"trace[753295515] transaction","detail":"{read_only:false; response_revision:3872; number_of_response:1; }","duration":"4.948773645s","start":"2026-05-18T00:43:35.923642Z","end":"2026-05-18T00:43:40.872416Z","steps":["trace[753295515] 'process raft request' (duration: 4.948493528s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.872563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.545385177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:43:40.872565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:35.923609Z","time spent":"4.948882596s","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T00:43:40.872617Z","caller":"traceutil/trace.go:171","msg":"trace[1593288128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3876; }","duration":"7.545491469s","start":"2026-05-18T00:43:33.327105Z","end":"2026-05-18T00:43:40.872597Z","steps":["trace[1593288128] 'agreement among raft nodes before linearized reading' (duration: 7.545396727s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.872654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:33.327079Z","time spent":"7.545567991s","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" "} {"level":"info","ts":"2026-05-18T00:43:40.872723Z","caller":"traceutil/trace.go:171","msg":"trace[1059094734] transaction","detail":"{read_only:false; response_revision:3873; number_of_response:1; }","duration":"4.940408628s","start":"2026-05-18T00:43:35.932289Z","end":"2026-05-18T00:43:40.872698Z","steps":["trace[1059094734] 'process raft request' (duration: 4.939979939s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.87293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:35.932259Z","time spent":"4.940596481s","remote":"127.0.0.1:33286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1113,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:40.873005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.747283657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-18T00:43:40.872996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.03816647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-18T00:43:40.87304Z","caller":"traceutil/trace.go:171","msg":"trace[618488229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3876; }","duration":"6.747350408s","start":"2026-05-18T00:43:34.125678Z","end":"2026-05-18T00:43:40.873029Z","steps":["trace[618488229] 'agreement among raft nodes before linearized reading' (duration: 6.747221125s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.125667Z","time spent":"6.747390069s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T00:43:40.873068Z","caller":"traceutil/trace.go:171","msg":"trace[1444014893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3876; }","duration":"4.038312603s","start":"2026-05-18T00:43:36.834741Z","end":"2026-05-18T00:43:40.873054Z","steps":["trace[1444014893] 'agreement among raft nodes before linearized reading' (duration: 4.038000046s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:36.834718Z","time spent":"4.038387104s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:43:40.873118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.74741154s","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-05-18T00:43:40.873084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.648595891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:40.873199Z","caller":"traceutil/trace.go:171","msg":"trace[1431536748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3876; }","duration":"6.747510982s","start":"2026-05-18T00:43:34.125657Z","end":"2026-05-18T00:43:40.873168Z","steps":["trace[1431536748] 'agreement among raft nodes before linearized reading' (duration: 6.747300917s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.125647Z","time spent":"6.747578572s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-18T00:43:40.873269Z","caller":"traceutil/trace.go:171","msg":"trace[141944925] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:3876; }","duration":"6.648876318s","start":"2026-05-18T00:43:34.22435Z","end":"2026-05-18T00:43:40.873227Z","steps":["trace[141944925] 'agreement among raft nodes before linearized reading' (duration: 6.648553421s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758325939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-05-18T00:43:40.873376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.224339Z","time spent":"6.64902012s","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-05-18T00:43:40.87339Z","caller":"traceutil/trace.go:171","msg":"trace[1492157748] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3876; }","duration":"1.758425472s","start":"2026-05-18T00:43:39.114952Z","end":"2026-05-18T00:43:40.873378Z","steps":["trace[1492157748] 'agreement among raft nodes before linearized reading' (duration: 1.7583046s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.114932Z","time spent":"1.758482043s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-18T00:43:40.873551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.359180103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.87359Z","caller":"traceutil/trace.go:171","msg":"trace[1146953070] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:3876; }","duration":"2.359264525s","start":"2026-05-18T00:43:38.514315Z","end":"2026-05-18T00:43:40.87358Z","steps":["trace[1146953070] 'agreement among raft nodes before linearized reading' (duration: 2.359155493s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:38.514294Z","time spent":"2.359315317s","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":61,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.873657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.922499122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-18T00:43:40.873707Z","caller":"traceutil/trace.go:171","msg":"trace[1732443925] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3876; }","duration":"3.922617725s","start":"2026-05-18T00:43:36.951081Z","end":"2026-05-18T00:43:40.873698Z","steps":["trace[1732443925] 'agreement among raft nodes before linearized reading' (duration: 3.922495602s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.790174772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.873736Z","caller":"traceutil/trace.go:171","msg":"trace[331038498] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:3876; }","duration":"3.790226893s","start":"2026-05-18T00:43:37.083502Z","end":"2026-05-18T00:43:40.873729Z","steps":["trace[331038498] 'agreement among raft nodes before linearized reading' (duration: 3.790179822s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:36.951052Z","time spent":"3.922676456s","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-18T00:43:40.873762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:37.083488Z","time spent":"3.790267745s","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.873764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.209159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.873803Z","caller":"traceutil/trace.go:171","msg":"trace[1176680487] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3876; }","duration":"739.293951ms","start":"2026-05-18T00:43:40.134499Z","end":"2026-05-18T00:43:40.873793Z","steps":["trace[1176680487] 'agreement among raft nodes before linearized reading' (duration: 739.21559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.873868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.027734843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-18T00:43:40.873945Z","caller":"traceutil/trace.go:171","msg":"trace[561145521] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:3876; }","duration":"2.027876167s","start":"2026-05-18T00:43:38.846052Z","end":"2026-05-18T00:43:40.873928Z","steps":["trace[561145521] 'agreement among raft nodes before linearized reading' (duration: 2.027481837s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.879224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:38.846037Z","time spent":"2.033170214s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-18T00:43:40.874013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696798251s","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-05-18T00:43:40.879561Z","caller":"traceutil/trace.go:171","msg":"trace[749883150] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:3876; }","duration":"1.702366416s","start":"2026-05-18T00:43:39.17716Z","end":"2026-05-18T00:43:40.879526Z","steps":["trace[749883150] 'agreement among raft nodes before linearized reading' (duration: 1.696819292s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.879601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.177138Z","time spent":"1.702451178s","remote":"127.0.0.1:48178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.874053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153006569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:40.879847Z","caller":"traceutil/trace.go:171","msg":"trace[1494638434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3876; }","duration":"1.15883983s","start":"2026-05-18T00:43:39.720997Z","end":"2026-05-18T00:43:40.879837Z","steps":["trace[1494638434] 'agreement among raft nodes before linearized reading' (duration: 1.1530337s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.880026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.720972Z","time spent":"1.159027045s","remote":"127.0.0.1:57456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T00:43:40.874095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57440598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.8802Z","caller":"traceutil/trace.go:171","msg":"trace[1098443720] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3876; }","duration":"1.580537876s","start":"2026-05-18T00:43:39.299652Z","end":"2026-05-18T00:43:40.88019Z","steps":["trace[1098443720] 'agreement among raft nodes before linearized reading' (duration: 1.57441551s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.880402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.299637Z","time spent":"1.580734371s","remote":"127.0.0.1:59032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.874117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.475311767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.880631Z","caller":"traceutil/trace.go:171","msg":"trace[1786229927] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3876; }","duration":"4.481876903s","start":"2026-05-18T00:43:36.398745Z","end":"2026-05-18T00:43:40.880622Z","steps":["trace[1786229927] 'agreement among raft nodes before linearized reading' (duration: 4.475098852s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.880662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:36.39872Z","time spent":"4.481931004s","remote":"127.0.0.1:57994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":38,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.874154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.285219602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:40.880804Z","caller":"traceutil/trace.go:171","msg":"trace[1822651162] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:3876; }","duration":"6.291909642s","start":"2026-05-18T00:43:34.588883Z","end":"2026-05-18T00:43:40.880793Z","steps":["trace[1822651162] 'agreement among raft nodes before linearized reading' (duration: 6.285247322s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.88085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:34.588839Z","time spent":"6.291982124s","remote":"127.0.0.1:42534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.874191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.785760854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:40.880979Z","caller":"traceutil/trace.go:171","msg":"trace[671913914] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3876; }","duration":"5.792585227s","start":"2026-05-18T00:43:35.088386Z","end":"2026-05-18T00:43:40.880971Z","steps":["trace[671913914] 'agreement among raft nodes before linearized reading' (duration: 5.785786084s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.881004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:35.088365Z","time spent":"5.792629857s","remote":"127.0.0.1:42590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-18T00:43:40.874377Z","caller":"traceutil/trace.go:171","msg":"trace[1018229963] transaction","detail":"{read_only:false; response_revision:3875; number_of_response:1; }","duration":"3.15550867s","start":"2026-05-18T00:43:37.718857Z","end":"2026-05-18T00:43:40.874366Z","steps":["trace[1018229963] 'process raft request' (duration: 3.153577437s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:40.874496Z","caller":"traceutil/trace.go:171","msg":"trace[1579348452] transaction","detail":"{read_only:false; response_revision:3874; number_of_response:1; }","duration":"4.446118754s","start":"2026-05-18T00:43:36.428368Z","end":"2026-05-18T00:43:40.874487Z","steps":["trace[1579348452] 'process raft request' (duration: 4.444007357s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.881975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:37.718831Z","time spent":"3.163049479s","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:43:40.882204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:36.428348Z","time spent":"4.453619001s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:40.874461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752756455s","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-05-18T00:43:40.882374Z","caller":"traceutil/trace.go:171","msg":"trace[1900673286] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3876; }","duration":"1.760753044s","start":"2026-05-18T00:43:39.1216Z","end":"2026-05-18T00:43:40.882353Z","steps":["trace[1900673286] 'agreement among raft nodes before linearized reading' (duration: 1.752739494s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.88244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.12139Z","time spent":"1.761025861s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-18T00:43:40.874642Z","caller":"traceutil/trace.go:171","msg":"trace[1017076615] transaction","detail":"{read_only:false; response_revision:3876; number_of_response:1; }","duration":"2.583946677s","start":"2026-05-18T00:43:38.290684Z","end":"2026-05-18T00:43:40.874631Z","steps":["trace[1017076615] 'process raft request' (duration: 2.581777748s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.882536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:38.290654Z","time spent":"2.591820872s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:40.874733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754444432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-18T00:43:40.882611Z","caller":"traceutil/trace.go:171","msg":"trace[743597810] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3876; }","duration":"1.762343479s","start":"2026-05-18T00:43:39.120257Z","end":"2026-05-18T00:43:40.8826Z","steps":["trace[743597810] 'agreement among raft nodes before linearized reading' (duration: 1.754412461s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.88265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.120245Z","time spent":"1.762394311s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-18T00:43:40.873845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.134473Z","time spent":"739.362002ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:40.875193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757159033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-18T00:43:40.882879Z","caller":"traceutil/trace.go:171","msg":"trace[2116945840] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3876; }","duration":"1.764857686s","start":"2026-05-18T00:43:39.117997Z","end":"2026-05-18T00:43:40.882854Z","steps":["trace[2116945840] 'agreement among raft nodes before linearized reading' (duration: 1.754697719s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:40.882918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:39.117987Z","time spent":"1.764920787s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-18T00:43:42.055673Z","caller":"traceutil/trace.go:171","msg":"trace[2120133557] transaction","detail":"{read_only:false; response_revision:3880; number_of_response:1; }","duration":"1.159851688s","start":"2026-05-18T00:43:40.89579Z","end":"2026-05-18T00:43:42.055642Z","steps":["trace[2120133557] 'process raft request' (duration: 1.159790647s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.055879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.895767Z","time spent":"1.160000632s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:43:42.05626Z","caller":"traceutil/trace.go:171","msg":"trace[714373952] transaction","detail":"{read_only:false; response_revision:3879; number_of_response:1; }","duration":"1.168254588s","start":"2026-05-18T00:43:40.887972Z","end":"2026-05-18T00:43:42.056227Z","steps":["trace[714373952] 'process raft request' (duration: 1.167558241s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:42.056308Z","caller":"traceutil/trace.go:171","msg":"trace[347507977] transaction","detail":"{read_only:false; response_revision:3877; number_of_response:1; }","duration":"1.174575458s","start":"2026-05-18T00:43:40.881697Z","end":"2026-05-18T00:43:42.056272Z","steps":["trace[347507977] 'process raft request' (duration: 1.143945861s)","trace[347507977] 'compare' (duration: 29.666726ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:42.056355Z","caller":"traceutil/trace.go:171","msg":"trace[520475697] linearizableReadLoop","detail":"{readStateIndex:4032; appliedIndex:4030; }","duration":"1.172311107s","start":"2026-05-18T00:43:40.883977Z","end":"2026-05-18T00:43:42.056288Z","steps":["trace[520475697] 'read index received' (duration: 10.26955ms)","trace[520475697] 'applied index is now lower than readState.Index' (duration: 1.162040117s)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:42.056402Z","caller":"traceutil/trace.go:171","msg":"trace[1085914682] transaction","detail":"{read_only:false; response_revision:3878; number_of_response:1; }","duration":"1.169020014s","start":"2026-05-18T00:43:40.887373Z","end":"2026-05-18T00:43:42.056393Z","steps":["trace[1085914682] 'process raft request' (duration: 1.168114083s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.056429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.88166Z","time spent":"1.174691931s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:42.056459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.887352Z","time spent":"1.169074316s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:42.056594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.887963Z","time spent":"1.1683734s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:43:42.056683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170280563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:43:42.056669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171617863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b082352d42d254\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-05-18T00:43:42.056705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168675897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-18T00:43:42.056726Z","caller":"traceutil/trace.go:171","msg":"trace[2127096003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3880; }","duration":"1.170333984s","start":"2026-05-18T00:43:40.88638Z","end":"2026-05-18T00:43:42.056714Z","steps":["trace[2127096003] 'agreement among raft nodes before linearized reading' (duration: 1.170261583s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:42.056749Z","caller":"traceutil/trace.go:171","msg":"trace[1408476723] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3880; }","duration":"1.168726758s","start":"2026-05-18T00:43:40.888009Z","end":"2026-05-18T00:43:42.056736Z","steps":["trace[1408476723] 'agreement among raft nodes before linearized reading' (duration: 1.168545214s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.056759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.886369Z","time spent":"1.170383695s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T00:43:42.056763Z","caller":"traceutil/trace.go:171","msg":"trace[1190980977] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b082352d42d254; range_end:; response_count:1; response_revision:3880; }","duration":"1.171714195s","start":"2026-05-18T00:43:40.885013Z","end":"2026-05-18T00:43:42.056727Z","steps":["trace[1190980977] 'agreement among raft nodes before linearized reading' (duration: 1.17153367s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.056781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.888002Z","time spent":"1.16877226s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-18T00:43:42.05681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.885006Z","time spent":"1.171795676s","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b082352d42d254\" "} {"level":"warn","ts":"2026-05-18T00:43:42.056872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169098396s","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-05-18T00:43:42.05691Z","caller":"traceutil/trace.go:171","msg":"trace[775075978] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3880; }","duration":"1.169170508s","start":"2026-05-18T00:43:40.887728Z","end":"2026-05-18T00:43:42.056899Z","steps":["trace[775075978] 'agreement among raft nodes before linearized reading' (duration: 1.169031764s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.056933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.782417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-18T00:43:42.056939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.887714Z","time spent":"1.169218438s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-18T00:43:42.056956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.28697ms","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-05-18T00:43:42.057004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154311345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:42.056964Z","caller":"traceutil/trace.go:171","msg":"trace[321469833] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:3880; }","duration":"944.853559ms","start":"2026-05-18T00:43:41.112103Z","end":"2026-05-18T00:43:42.056956Z","steps":["trace[321469833] 'agreement among raft nodes before linearized reading' (duration: 944.786747ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:42.057045Z","caller":"traceutil/trace.go:171","msg":"trace[1061380251] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3880; }","duration":"1.154349967s","start":"2026-05-18T00:43:40.902685Z","end":"2026-05-18T00:43:42.057035Z","steps":["trace[1061380251] 'agreement among raft nodes before linearized reading' (duration: 1.154297376s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:41.112086Z","time spent":"944.959752ms","remote":"127.0.0.1:57928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:42.057072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.902446Z","time spent":"1.154619852s","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" "} {"level":"warn","ts":"2026-05-18T00:43:42.057084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170917367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/kube-prometheus-stack.18b08234c244044b\" ","response":"range_response_count:1 size:721"} {"level":"warn","ts":"2026-05-18T00:43:42.057094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169638669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-05-18T00:43:42.057113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.859828ms","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-05-18T00:43:42.057134Z","caller":"traceutil/trace.go:171","msg":"trace[729529765] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3880; }","duration":"1.16969372s","start":"2026-05-18T00:43:40.887425Z","end":"2026-05-18T00:43:42.057119Z","steps":["trace[729529765] 'agreement among raft nodes before linearized reading' (duration: 1.169544986s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:42.057145Z","caller":"traceutil/trace.go:171","msg":"trace[1906647821] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3880; }","duration":"716.890028ms","start":"2026-05-18T00:43:41.340244Z","end":"2026-05-18T00:43:42.057134Z","steps":["trace[1906647821] 'agreement among raft nodes before linearized reading' (duration: 716.788656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:41.340204Z","time spent":"716.965089ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":91,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:43:42.057188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172102302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-05-18T00:43:42.057165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.887419Z","time spent":"1.16973974s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-18T00:43:42.057214Z","caller":"traceutil/trace.go:171","msg":"trace[1876481466] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3880; }","duration":"1.172144534s","start":"2026-05-18T00:43:40.885061Z","end":"2026-05-18T00:43:42.057206Z","steps":["trace[1876481466] 'agreement among raft nodes before linearized reading' (duration: 1.172072623s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.885053Z","time spent":"1.172187455s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-18T00:43:42.057116Z","caller":"traceutil/trace.go:171","msg":"trace[1288687507] range","detail":"{range_begin:/registry/events/default/kube-prometheus-stack.18b08234c244044b; range_end:; response_count:1; response_revision:3880; }","duration":"1.170960698s","start":"2026-05-18T00:43:40.886145Z","end":"2026-05-18T00:43:42.057106Z","steps":["trace[1288687507] 'agreement among raft nodes before linearized reading' (duration: 1.170867675s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.003745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-18T00:43:42.057309Z","caller":"traceutil/trace.go:171","msg":"trace[1108153830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3880; }","duration":"930.099037ms","start":"2026-05-18T00:43:41.127201Z","end":"2026-05-18T00:43:42.0573Z","steps":["trace[1108153830] 'agreement among raft nodes before linearized reading' (duration: 930.013765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.886139Z","time spent":"1.171162522s","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":745,"request content":"key:\"/registry/events/default/kube-prometheus-stack.18b08234c244044b\" "} {"level":"warn","ts":"2026-05-18T00:43:42.057336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:41.127122Z","time spent":"930.20781ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T00:43:42.057001Z","caller":"traceutil/trace.go:171","msg":"trace[1478615773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3880; }","duration":"933.349741ms","start":"2026-05-18T00:43:41.123639Z","end":"2026-05-18T00:43:42.056988Z","steps":["trace[1478615773] 'agreement among raft nodes before linearized reading' (duration: 933.132046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:41.123629Z","time spent":"933.785841ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T00:43:42.056669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172680417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-18T00:43:42.057529Z","caller":"traceutil/trace.go:171","msg":"trace[298484067] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3880; }","duration":"1.173579067s","start":"2026-05-18T00:43:40.883933Z","end":"2026-05-18T00:43:42.057512Z","steps":["trace[298484067] 'agreement among raft nodes before linearized reading' (duration: 1.172585485s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:42.057579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:43:40.883913Z","time spent":"1.173653878s","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-18T00:43:42.580236Z","caller":"traceutil/trace.go:171","msg":"trace[803132244] transaction","detail":"{read_only:false; response_revision:3890; number_of_response:1; }","duration":"110.792216ms","start":"2026-05-18T00:43:42.469408Z","end":"2026-05-18T00:43:42.5802Z","steps":["trace[803132244] 'process raft request' (duration: 57.128982ms)","trace[803132244] 'compare' (duration: 53.449618ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:42.744336Z","caller":"traceutil/trace.go:171","msg":"trace[1014532121] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"121.019685ms","start":"2026-05-18T00:43:42.623263Z","end":"2026-05-18T00:43:42.744283Z","steps":["trace[1014532121] 'process raft request' (duration: 57.678884ms)","trace[1014532121] 'compare' (duration: 62.444461ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:43.39719Z","caller":"traceutil/trace.go:171","msg":"trace[1881209687] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"184.482731ms","start":"2026-05-18T00:43:43.212681Z","end":"2026-05-18T00:43:43.397163Z","steps":["trace[1881209687] 'process raft request' (duration: 183.979189ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:43.551472Z","caller":"traceutil/trace.go:171","msg":"trace[84977589] linearizableReadLoop","detail":"{readStateIndex:4057; appliedIndex:4056; }","duration":"105.063818ms","start":"2026-05-18T00:43:43.446388Z","end":"2026-05-18T00:43:43.551452Z","steps":["trace[84977589] 'read index received' (duration: 33.536693ms)","trace[84977589] 'applied index is now lower than readState.Index' (duration: 71.526545ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:43.551828Z","caller":"traceutil/trace.go:171","msg":"trace[19076248] transaction","detail":"{read_only:false; response_revision:3902; number_of_response:1; }","duration":"266.650245ms","start":"2026-05-18T00:43:43.285068Z","end":"2026-05-18T00:43:43.551718Z","steps":["trace[19076248] 'process raft request' (duration: 195.231252ms)","trace[19076248] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; req_size:328615; } (duration: 70.399241ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:43:43.551848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.434948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:43.551911Z","caller":"traceutil/trace.go:171","msg":"trace[1182965547] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com; range_end:; response_count:0; response_revision:3902; }","duration":"105.54904ms","start":"2026-05-18T00:43:43.446347Z","end":"2026-05-18T00:43:43.551896Z","steps":["trace[1182965547] 'agreement among raft nodes before linearized reading' (duration: 105.418696ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:43.735972Z","caller":"traceutil/trace.go:171","msg":"trace[616780186] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"108.116667ms","start":"2026-05-18T00:43:43.627822Z","end":"2026-05-18T00:43:43.735938Z","steps":["trace[616780186] 'process raft request' (duration: 31.681851ms)","trace[616780186] 'compare' (duration: 76.286962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:44:58.873333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.161651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:44:58.873454Z","caller":"traceutil/trace.go:171","msg":"trace[1910270788] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"158.82713ms","start":"2026-05-18T00:44:58.714606Z","end":"2026-05-18T00:44:58.873433Z","steps":["trace[1910270788] 'read index received' (duration: 1.382173ms)","trace[1910270788] 'applied index is now lower than readState.Index' (duration: 157.443897ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:44:58.873604Z","caller":"traceutil/trace.go:171","msg":"trace[641497699] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"201.004553ms","start":"2026-05-18T00:44:58.672562Z","end":"2026-05-18T00:44:58.873566Z","steps":["trace[641497699] 'process raft request' (duration: 43.499794ms)","trace[641497699] 'compare' (duration: 157.036638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:44:58.87378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.182758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:44:58.873809Z","caller":"traceutil/trace.go:171","msg":"trace[1526470978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5042; }","duration":"159.260518ms","start":"2026-05-18T00:44:58.714539Z","end":"2026-05-18T00:44:58.8738Z","steps":["trace[1526470978] 'agreement among raft nodes before linearized reading' (duration: 159.203267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:44:58.874288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.432217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-18T00:44:58.874358Z","caller":"traceutil/trace.go:171","msg":"trace[1396713498] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5042; }","duration":"144.504218ms","start":"2026-05-18T00:44:58.72984Z","end":"2026-05-18T00:44:58.874344Z","steps":["trace[1396713498] 'agreement among raft nodes before linearized reading' (duration: 144.330134ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:55.471642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2915} {"level":"info","ts":"2026-05-18T00:45:55.572366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2915,"took":"98.423673ms","hash":3142075069,"current-db-size-bytes":53383168,"current-db-size":"53 MB","current-db-size-in-use-bytes":28520448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T00:45:55.572434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3142075069,"revision":2915,"compact-revision":-1} {"level":"info","ts":"2026-05-18T00:50:55.478509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5524} {"level":"info","ts":"2026-05-18T00:50:55.558908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5524,"took":"78.279149ms","hash":4061218851,"current-db-size-bytes":53383168,"current-db-size":"53 MB","current-db-size-in-use-bytes":18313216,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-18T00:50:55.558978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4061218851,"revision":5524,"compact-revision":2915}