{"level":"warn","ts":"2026-05-10T00:43:53.062668Z","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-10T00:43:53.062771Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-10T00:43:53.062849Z","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-10T00:43:53.062858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-10T00:43:53.062879Z","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-10T00:43:53.06519Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-05-10T00:43:53.067188Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-10T00:43:53.077328Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.199433ms"} {"level":"info","ts":"2026-05-10T00:43:53.087836Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-05-10T00:43:53.087926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:43:53.087961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-05-10T00:43:53.087978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:43:53.087988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-05-10T00:43:53.088026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-05-10T00:43:53.093369Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:43:53.094452Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:43:53.09628Z","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-10T00:43:53.098896Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:43:53.100395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:43:53.100565Z","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-10T00:43:53.101164Z","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-10T00:43:53.101187Z","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-10T00:43:53.101567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-05-10T00:43:53.101776Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-10T00:43:53.10579Z","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-10T00:43:53.105889Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-10T00:43:53.105973Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-10T00:43:53.106064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:43:53.106168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:43:53.988612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:43:53.988659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:43:53.98869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-05-10T00:43:53.98871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:43:53.988718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-10T00:43:53.988734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-05-10T00:43:53.988743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-10T00:43:53.989583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:43:53.990506Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:43:53.990842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:43:53.990858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:43:53.991207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:43:53.991238Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:43:53.991327Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:43:53.991344Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:43:53.991353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:43:53.993446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:43:53.994264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-05-10T00:44:10.146492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.825713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T00:44:10.146647Z","caller":"traceutil/trace.go:171","msg":"trace[570230273] transaction","detail":"{read_only:false; number_of_response:1; response_revision:384; }","duration":"298.091507ms","start":"2026-05-10T00:44:09.848536Z","end":"2026-05-10T00:44:10.146628Z","steps":["trace[570230273] 'process raft request' (duration: 104.545162ms)","trace[570230273] 'compare' (duration: 192.374735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:44:10.514095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.695891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:44:10.514398Z","caller":"traceutil/trace.go:171","msg":"trace[565044607] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"659.882246ms","start":"2026-05-10T00:44:09.854495Z","end":"2026-05-10T00:44:10.514378Z","steps":["trace[565044607] 'process raft request' (duration: 659.824276ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:10.514384Z","caller":"traceutil/trace.go:171","msg":"trace[885404759] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"661.957935ms","start":"2026-05-10T00:44:09.852371Z","end":"2026-05-10T00:44:10.514329Z","steps":["trace[885404759] 'process raft request' (duration: 433.776885ms)","trace[885404759] 'compare' (duration: 227.542958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:44:10.514493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:09.85449Z","time spent":"659.954458ms","remote":"127.0.0.1:60458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:44:10.51457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:09.85233Z","time spent":"662.150027ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:44:10.51494Z","caller":"traceutil/trace.go:171","msg":"trace[359873005] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"660.497498ms","start":"2026-05-10T00:44:09.854429Z","end":"2026-05-10T00:44:10.514926Z","steps":["trace[359873005] 'process raft request' (duration: 659.811426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:10.515027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:09.854412Z","time spent":"660.564659ms","remote":"127.0.0.1:60420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4157,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:44:10.557596Z","caller":"traceutil/trace.go:171","msg":"trace[1173717032] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"405.647227ms","start":"2026-05-10T00:44:10.151919Z","end":"2026-05-10T00:44:10.557566Z","steps":["trace[1173717032] 'process raft request' (duration: 405.360593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:10.55778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:10.151892Z","time spent":"405.791711ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:44:10.557613Z","caller":"traceutil/trace.go:171","msg":"trace[602935893] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"402.768625ms","start":"2026-05-10T00:44:10.154822Z","end":"2026-05-10T00:44:10.55759Z","steps":["trace[602935893] 'process raft request' (duration: 402.694695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:10.558267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:10.154806Z","time spent":"403.302336ms","remote":"127.0.0.1:60458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:44:10.729075Z","caller":"traceutil/trace.go:171","msg":"trace[182270430] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"209.465054ms","start":"2026-05-10T00:44:10.519573Z","end":"2026-05-10T00:44:10.729038Z","steps":["trace[182270430] 'process raft request' (duration: 205.799628ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:10.729394Z","caller":"traceutil/trace.go:171","msg":"trace[1057264705] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:404; }","duration":"162.818765ms","start":"2026-05-10T00:44:10.566542Z","end":"2026-05-10T00:44:10.729361Z","steps":["trace[1057264705] 'read index received' (duration: 158.882374ms)","trace[1057264705] 'applied index is now lower than readState.Index' (duration: 3.935411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:44:10.729581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.017808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-05-10T00:44:10.729623Z","caller":"traceutil/trace.go:171","msg":"trace[1006351992] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:391; }","duration":"163.103741ms","start":"2026-05-10T00:44:10.56651Z","end":"2026-05-10T00:44:10.729614Z","steps":["trace[1006351992] 'agreement among raft nodes before linearized reading' (duration: 162.955738ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:10.729686Z","caller":"traceutil/trace.go:171","msg":"trace[2117719467] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"203.430085ms","start":"2026-05-10T00:44:10.526204Z","end":"2026-05-10T00:44:10.729634Z","steps":["trace[2117719467] 'process raft request' (duration: 203.027428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:22.913042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.937312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:44:22.913197Z","caller":"traceutil/trace.go:171","msg":"trace[1970728301] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"151.878052ms","start":"2026-05-10T00:44:22.761296Z","end":"2026-05-10T00:44:22.913174Z","steps":["trace[1970728301] 'process raft request' (duration: 28.661376ms)","trace[1970728301] 'compare' (duration: 122.580075ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:44:22.919677Z","caller":"traceutil/trace.go:171","msg":"trace[610879449] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"152.145196ms","start":"2026-05-10T00:44:22.767517Z","end":"2026-05-10T00:44:22.919662Z","steps":["trace[610879449] 'process raft request' (duration: 152.065975ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:22.919961Z","caller":"traceutil/trace.go:171","msg":"trace[572449285] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"153.03701ms","start":"2026-05-10T00:44:22.766914Z","end":"2026-05-10T00:44:22.919952Z","steps":["trace[572449285] 'process raft request' (duration: 152.475081ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:23.502576Z","caller":"traceutil/trace.go:171","msg":"trace[1630374614] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"158.67565ms","start":"2026-05-10T00:44:23.343866Z","end":"2026-05-10T00:44:23.502541Z","steps":["trace[1630374614] 'process raft request' (duration: 158.439366ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:23.506539Z","caller":"traceutil/trace.go:171","msg":"trace[2074523474] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"136.217419ms","start":"2026-05-10T00:44:23.370299Z","end":"2026-05-10T00:44:23.506516Z","steps":["trace[2074523474] 'process raft request' (duration: 135.905994ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:23.506806Z","caller":"traceutil/trace.go:171","msg":"trace[1212360816] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"130.055258ms","start":"2026-05-10T00:44:23.376423Z","end":"2026-05-10T00:44:23.506478Z","steps":["trace[1212360816] 'process raft request' (duration: 130.010096ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:24.63407Z","caller":"traceutil/trace.go:171","msg":"trace[1647508844] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"101.63925ms","start":"2026-05-10T00:44:24.532395Z","end":"2026-05-10T00:44:24.634034Z","steps":["trace[1647508844] 'read index received' (duration: 87.745621ms)","trace[1647508844] 'applied index is now lower than readState.Index' (duration: 13.892159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:44:24.634557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.128347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-05-10T00:44:24.634613Z","caller":"traceutil/trace.go:171","msg":"trace[560180327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:543; }","duration":"102.2796ms","start":"2026-05-10T00:44:24.532316Z","end":"2026-05-10T00:44:24.634596Z","steps":["trace[560180327] 'agreement among raft nodes before linearized reading' (duration: 101.963865ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:24.634585Z","caller":"traceutil/trace.go:171","msg":"trace[1682953171] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"102.700006ms","start":"2026-05-10T00:44:24.53184Z","end":"2026-05-10T00:44:24.63454Z","steps":["trace[1682953171] 'process raft request' (duration: 88.344641ms)","trace[1682953171] 'compare' (duration: 13.692955ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:44:29.975593Z","caller":"traceutil/trace.go:171","msg":"trace[393380089] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"292.430954ms","start":"2026-05-10T00:44:29.683102Z","end":"2026-05-10T00:44:29.975533Z","steps":["trace[393380089] 'read index received' (duration: 290.999111ms)","trace[393380089] 'applied index is now lower than readState.Index' (duration: 1.430783ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:44:29.975742Z","caller":"traceutil/trace.go:171","msg":"trace[194976217] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"476.658984ms","start":"2026-05-10T00:44:29.499012Z","end":"2026-05-10T00:44:29.975671Z","steps":["trace[194976217] 'process raft request' (duration: 475.521286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:29.975881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.848749ms","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-10T00:44:29.975931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.846559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jlhcg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-10T00:44:29.975956Z","caller":"traceutil/trace.go:171","msg":"trace[1953907344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"285.965809ms","start":"2026-05-10T00:44:29.689975Z","end":"2026-05-10T00:44:29.975941Z","steps":["trace[1953907344] 'agreement among raft nodes before linearized reading' (duration: 285.804617ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:44:29.975994Z","caller":"traceutil/trace.go:171","msg":"trace[1741555667] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jlhcg; range_end:; response_count:1; response_revision:574; }","duration":"247.90918ms","start":"2026-05-10T00:44:29.728062Z","end":"2026-05-10T00:44:29.975971Z","steps":["trace[1741555667] 'agreement among raft nodes before linearized reading' (duration: 247.769037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:29.976075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:44:29.49897Z","time spent":"476.932999ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:44:29.976094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.568007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-10T00:44:29.976204Z","caller":"traceutil/trace.go:171","msg":"trace[1159308691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"284.719931ms","start":"2026-05-10T00:44:29.691454Z","end":"2026-05-10T00:44:29.976174Z","steps":["trace[1159308691] 'agreement among raft nodes before linearized reading' (duration: 284.490646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:29.976884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.751725ms","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-10T00:44:29.976997Z","caller":"traceutil/trace.go:171","msg":"trace[1332637289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"293.920357ms","start":"2026-05-10T00:44:29.683054Z","end":"2026-05-10T00:44:29.976974Z","steps":["trace[1332637289] 'agreement among raft nodes before linearized reading' (duration: 292.84085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:44:45.653519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.735722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T00:44:45.653608Z","caller":"traceutil/trace.go:171","msg":"trace[1399731179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"207.874414ms","start":"2026-05-10T00:44:45.445716Z","end":"2026-05-10T00:44:45.65359Z","steps":["trace[1399731179] 'range keys from in-memory index tree' (duration: 207.55532ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:45:15.563378Z","caller":"traceutil/trace.go:171","msg":"trace[1233034901] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"175.70685ms","start":"2026-05-10T00:45:15.387641Z","end":"2026-05-10T00:45:15.563348Z","steps":["trace[1233034901] 'process raft request' (duration: 175.495742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:03.473657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.354431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:46:03.473741Z","caller":"traceutil/trace.go:171","msg":"trace[1341840792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1818; }","duration":"340.498515ms","start":"2026-05-10T00:46:03.133224Z","end":"2026-05-10T00:46:03.473723Z","steps":["trace[1341840792] 'range keys from in-memory index tree' (duration: 340.268418ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:03.473721Z","caller":"traceutil/trace.go:171","msg":"trace[1455399932] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"280.140319ms","start":"2026-05-10T00:46:03.193553Z","end":"2026-05-10T00:46:03.473693Z","steps":["trace[1455399932] 'read index received' (duration: 225.51443ms)","trace[1455399932] 'applied index is now lower than readState.Index' (duration: 54.624429ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:46:03.473791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:03.133208Z","time spent":"340.569788ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:46:03.47376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.794887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ae0db209e52c26\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-05-10T00:46:03.473805Z","caller":"traceutil/trace.go:171","msg":"trace[1421417847] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"340.254259ms","start":"2026-05-10T00:46:03.133535Z","end":"2026-05-10T00:46:03.47379Z","steps":["trace[1421417847] 'process raft request' (duration: 285.601718ms)","trace[1421417847] 'compare' (duration: 54.437165ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:46:03.473865Z","caller":"traceutil/trace.go:171","msg":"trace[596483109] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ae0db209e52c26; range_end:; response_count:1; response_revision:1818; }","duration":"386.890629ms","start":"2026-05-10T00:46:03.086938Z","end":"2026-05-10T00:46:03.473829Z","steps":["trace[596483109] 'range keys from in-memory index tree' (duration: 386.659624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:03.473915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:03.133509Z","time spent":"340.350241ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:46:03.473921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:03.086896Z","time spent":"387.013274ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ae0db209e52c26\" "} {"level":"warn","ts":"2026-05-10T00:46:03.473939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.375545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aafe9a6d-5aec-4ef1-b876-9184fc20da50\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-10T00:46:03.474109Z","caller":"traceutil/trace.go:171","msg":"trace[713129864] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aafe9a6d-5aec-4ef1-b876-9184fc20da50; range_end:; response_count:1; response_revision:1819; }","duration":"280.572771ms","start":"2026-05-10T00:46:03.193519Z","end":"2026-05-10T00:46:03.474091Z","steps":["trace[713129864] 'agreement among raft nodes before linearized reading' (duration: 280.323244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:03.474276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.325064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aafe9a6d-5aec-4ef1-b876-9184fc20da50\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-10T00:46:03.474547Z","caller":"traceutil/trace.go:171","msg":"trace[25131334] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aafe9a6d-5aec-4ef1-b876-9184fc20da50; range_end:; response_count:1; response_revision:1819; }","duration":"254.610404ms","start":"2026-05-10T00:46:03.219918Z","end":"2026-05-10T00:46:03.474528Z","steps":["trace[25131334] 'agreement among raft nodes before linearized reading' (duration: 254.222203ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:03.831246Z","caller":"traceutil/trace.go:171","msg":"trace[2073718767] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1893; }","duration":"258.945581ms","start":"2026-05-10T00:46:03.572278Z","end":"2026-05-10T00:46:03.831223Z","steps":["trace[2073718767] 'read index received' (duration: 258.706535ms)","trace[2073718767] 'applied index is now lower than readState.Index' (duration: 237.806µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:46:03.831814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.949186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-10T00:46:03.831908Z","caller":"traceutil/trace.go:171","msg":"trace[2026359850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1822; }","duration":"235.032848ms","start":"2026-05-10T00:46:03.596856Z","end":"2026-05-10T00:46:03.831889Z","steps":["trace[2026359850] 'agreement among raft nodes before linearized reading' (duration: 234.896995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:03.831923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.998964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:46:03.832066Z","caller":"traceutil/trace.go:171","msg":"trace[2008195921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1822; }","duration":"127.232789ms","start":"2026-05-10T00:46:03.704813Z","end":"2026-05-10T00:46:03.832046Z","steps":["trace[2008195921] 'agreement among raft nodes before linearized reading' (duration: 126.997593ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:03.831327Z","caller":"traceutil/trace.go:171","msg":"trace[345720213] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"284.56193ms","start":"2026-05-10T00:46:03.546736Z","end":"2026-05-10T00:46:03.831298Z","steps":["trace[345720213] 'process raft request' (duration: 284.346534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:03.831587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.291021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-10T00:46:03.832422Z","caller":"traceutil/trace.go:171","msg":"trace[185531458] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1822; }","duration":"260.188585ms","start":"2026-05-10T00:46:03.57222Z","end":"2026-05-10T00:46:03.832409Z","steps":["trace[185531458] 'agreement among raft nodes before linearized reading' (duration: 259.203198ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:17.971776Z","caller":"traceutil/trace.go:171","msg":"trace[1515614272] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"392.795181ms","start":"2026-05-10T00:46:17.578963Z","end":"2026-05-10T00:46:17.971759Z","steps":["trace[1515614272] 'process raft request' (duration: 392.451703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:17.97191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:17.578939Z","time spent":"392.900185ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:46:38.378547Z","caller":"traceutil/trace.go:171","msg":"trace[457591718] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"235.373303ms","start":"2026-05-10T00:46:38.143149Z","end":"2026-05-10T00:46:38.378523Z","steps":["trace[457591718] 'process raft request' (duration: 235.22129ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:38.393747Z","caller":"traceutil/trace.go:171","msg":"trace[842196475] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"242.439285ms","start":"2026-05-10T00:46:38.151278Z","end":"2026-05-10T00:46:38.393718Z","steps":["trace[842196475] 'process raft request' (duration: 241.986184ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:38.393856Z","caller":"traceutil/trace.go:171","msg":"trace[1053295589] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"242.121878ms","start":"2026-05-10T00:46:38.151721Z","end":"2026-05-10T00:46:38.393843Z","steps":["trace[1053295589] 'process raft request' (duration: 241.736879ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:38.393964Z","caller":"traceutil/trace.go:171","msg":"trace[540654374] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2145; }","duration":"180.079296ms","start":"2026-05-10T00:46:38.213872Z","end":"2026-05-10T00:46:38.393951Z","steps":["trace[540654374] 'read index received' (duration: 164.932792ms)","trace[540654374] 'applied index is now lower than readState.Index' (duration: 14.932619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:46:38.394178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.282162ms","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-10T00:46:38.394262Z","caller":"traceutil/trace.go:171","msg":"trace[268799897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2068; }","duration":"180.415063ms","start":"2026-05-10T00:46:38.213827Z","end":"2026-05-10T00:46:38.394242Z","steps":["trace[268799897] 'agreement among raft nodes before linearized reading' (duration: 180.152398ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:38.9058Z","caller":"traceutil/trace.go:171","msg":"trace[798331869] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"157.441141ms","start":"2026-05-10T00:46:38.748336Z","end":"2026-05-10T00:46:38.905777Z","steps":["trace[798331869] 'process raft request' (duration: 157.318739ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:39.124946Z","caller":"traceutil/trace.go:171","msg":"trace[863282996] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"376.048243ms","start":"2026-05-10T00:46:38.748872Z","end":"2026-05-10T00:46:39.12492Z","steps":["trace[863282996] 'process raft request' (duration: 375.972591ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:46:39.125008Z","caller":"traceutil/trace.go:171","msg":"trace[1096126308] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"376.589144ms","start":"2026-05-10T00:46:38.748362Z","end":"2026-05-10T00:46:39.124951Z","steps":["trace[1096126308] 'process raft request' (duration: 376.324879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:39.125191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:38.748338Z","time spent":"376.757959ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:46:39.125313Z","caller":"traceutil/trace.go:171","msg":"trace[1424437981] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"369.842431ms","start":"2026-05-10T00:46:38.755458Z","end":"2026-05-10T00:46:39.1253Z","steps":["trace[1424437981] 'process raft request' (duration: 369.425421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:46:39.125361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:38.755441Z","time spent":"369.892502ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:46:39.125773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:46:38.748848Z","time spent":"376.196215ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:48:18.844247Z","caller":"traceutil/trace.go:171","msg":"trace[1013152806] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"109.407515ms","start":"2026-05-10T00:48:18.734818Z","end":"2026-05-10T00:48:18.844225Z","steps":["trace[1013152806] 'process raft request' (duration: 109.246912ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:18.851397Z","caller":"traceutil/trace.go:171","msg":"trace[1728731343] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"100.534185ms","start":"2026-05-10T00:48:18.750842Z","end":"2026-05-10T00:48:18.851377Z","steps":["trace[1728731343] 'process raft request' (duration: 100.062187ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:19.046176Z","caller":"traceutil/trace.go:171","msg":"trace[1484285007] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"135.290117ms","start":"2026-05-10T00:48:18.910845Z","end":"2026-05-10T00:48:19.046135Z","steps":["trace[1484285007] 'process raft request' (duration: 134.969551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:19.328801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.897669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:48:19.328914Z","caller":"traceutil/trace.go:171","msg":"trace[1747755542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2713; }","duration":"195.060381ms","start":"2026-05-10T00:48:19.133831Z","end":"2026-05-10T00:48:19.328891Z","steps":["trace[1747755542] 'range keys from in-memory index tree' (duration: 194.813886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.4765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.446061Z","time spent":"1.030430824s","remote":"127.0.0.1:46286","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-10T00:52:16.47759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.340665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:16.47826Z","caller":"traceutil/trace.go:171","msg":"trace[1113840046] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"691.08319ms","start":"2026-05-10T00:52:15.787156Z","end":"2026-05-10T00:52:16.478239Z","steps":["trace[1113840046] 'process raft request' (duration: 690.575678ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:16.478353Z","caller":"traceutil/trace.go:171","msg":"trace[123035087] linearizableReadLoop","detail":"{readStateIndex:4993; appliedIndex:4992; }","duration":"864.14004ms","start":"2026-05-10T00:52:15.614196Z","end":"2026-05-10T00:52:16.478336Z","steps":["trace[123035087] 'read index received' (duration: 151.878691ms)","trace[123035087] 'applied index is now lower than readState.Index' (duration: 712.260129ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:16.478339Z","caller":"traceutil/trace.go:171","msg":"trace[1075529715] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"987.191477ms","start":"2026-05-10T00:52:15.49112Z","end":"2026-05-10T00:52:16.478312Z","steps":["trace[1075529715] 'process raft request' (duration: 275.038082ms)","trace[1075529715] 'compare' (duration: 710.950145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:16.478347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.787131Z","time spent":"691.177052ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:16.478539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.328845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-w9kwz.18ae0e06087e4411\" ","response":"range_response_count:1 size:906"} {"level":"warn","ts":"2026-05-10T00:52:16.478526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.491102Z","time spent":"987.329381ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:16.478565Z","caller":"traceutil/trace.go:171","msg":"trace[1060907654] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-w9kwz.18ae0e06087e4411; range_end:; response_count:1; response_revision:4812; }","duration":"864.393926ms","start":"2026-05-10T00:52:15.614163Z","end":"2026-05-10T00:52:16.478557Z","steps":["trace[1060907654] 'agreement among raft nodes before linearized reading' (duration: 864.250533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.478589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.614149Z","time spent":"864.434448ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":928,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-w9kwz.18ae0e06087e4411\" "} {"level":"info","ts":"2026-05-10T00:52:16.724511Z","caller":"traceutil/trace.go:171","msg":"trace[1112229534] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"936.855048ms","start":"2026-05-10T00:52:15.787632Z","end":"2026-05-10T00:52:16.724488Z","steps":["trace[1112229534] 'process raft request' (duration: 936.652733ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:16.724676Z","caller":"traceutil/trace.go:171","msg":"trace[944836871] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4994; }","duration":"246.250048ms","start":"2026-05-10T00:52:16.478394Z","end":"2026-05-10T00:52:16.724644Z","steps":["trace[944836871] 'read index received' (duration: 245.850658ms)","trace[944836871] 'applied index is now lower than readState.Index' (duration: 396.18µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:16.724808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.114333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-10T00:52:16.724855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.385285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:16.724892Z","caller":"traceutil/trace.go:171","msg":"trace[315174013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4814; }","duration":"590.481807ms","start":"2026-05-10T00:52:16.1344Z","end":"2026-05-10T00:52:16.724882Z","steps":["trace[315174013] 'agreement among raft nodes before linearized reading' (duration: 590.379294ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:16.72491Z","caller":"traceutil/trace.go:171","msg":"trace[1810772469] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4814; }","duration":"266.287336ms","start":"2026-05-10T00:52:16.458608Z","end":"2026-05-10T00:52:16.724896Z","steps":["trace[1810772469] 'agreement among raft nodes before linearized reading' (duration: 266.062111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.724933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.134384Z","time spent":"590.540948ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:52:16.725124Z","caller":"traceutil/trace.go:171","msg":"trace[548619265] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"881.848182ms","start":"2026-05-10T00:52:15.843255Z","end":"2026-05-10T00:52:16.725103Z","steps":["trace[548619265] 'process raft request' (duration: 881.190766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.725254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.825402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-10T00:52:16.725295Z","caller":"traceutil/trace.go:171","msg":"trace[745027937] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e; range_end:; response_count:1; response_revision:4814; }","duration":"662.905965ms","start":"2026-05-10T00:52:16.062377Z","end":"2026-05-10T00:52:16.725283Z","steps":["trace[745027937] 'agreement among raft nodes before linearized reading' (duration: 662.773522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.725277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.787618Z","time spent":"936.965609ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:16.725414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.785319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T00:52:16.725466Z","caller":"traceutil/trace.go:171","msg":"trace[1214848712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4814; }","duration":"682.868232ms","start":"2026-05-10T00:52:16.042589Z","end":"2026-05-10T00:52:16.725457Z","steps":["trace[1214848712] 'agreement among raft nodes before linearized reading' (duration: 682.762359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.725503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.04257Z","time spent":"682.923712ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:52:16.725651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055553947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-10T00:52:16.725535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.219817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:52:16.725855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.238083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:52:16.725936Z","caller":"traceutil/trace.go:171","msg":"trace[1700439713] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4814; }","duration":"959.355097ms","start":"2026-05-10T00:52:15.76657Z","end":"2026-05-10T00:52:16.725926Z","steps":["trace[1700439713] 'agreement among raft nodes before linearized reading' (duration: 959.209773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.725331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.062353Z","time spent":"662.970846ms","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e\" "} {"level":"warn","ts":"2026-05-10T00:52:16.725998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.766546Z","time spent":"959.413367ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:52:16.726053Z","caller":"traceutil/trace.go:171","msg":"trace[303718112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4814; }","duration":"390.045987ms","start":"2026-05-10T00:52:16.33588Z","end":"2026-05-10T00:52:16.725926Z","steps":["trace[303718112] 'agreement among raft nodes before linearized reading' (duration: 389.017931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.726352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.335858Z","time spent":"390.472988ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:52:16.725694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.433074ms","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-10T00:52:16.726516Z","caller":"traceutil/trace.go:171","msg":"trace[55216242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4814; }","duration":"388.296183ms","start":"2026-05-10T00:52:16.338204Z","end":"2026-05-10T00:52:16.7265Z","steps":["trace[55216242] 'agreement among raft nodes before linearized reading' (duration: 387.3339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.726572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.338181Z","time spent":"388.377587ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-10T00:52:16.725752Z","caller":"traceutil/trace.go:171","msg":"trace[1012476771] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e; range_end:; response_count:1; response_revision:4814; }","duration":"1.055609359s","start":"2026-05-10T00:52:15.670081Z","end":"2026-05-10T00:52:16.725691Z","steps":["trace[1012476771] 'agreement among raft nodes before linearized reading' (duration: 1.055386414s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.72674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.670021Z","time spent":"1.056708466s","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46eacbeb-999b-4a61-90c5-c983e659f07e\" "} {"level":"warn","ts":"2026-05-10T00:52:16.725681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:15.843229Z","time spent":"881.976335ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:16.725862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.122835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-10T00:52:16.726993Z","caller":"traceutil/trace.go:171","msg":"trace[1924898182] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4814; }","duration":"570.303896ms","start":"2026-05-10T00:52:16.156673Z","end":"2026-05-10T00:52:16.726977Z","steps":["trace[1924898182] 'agreement among raft nodes before linearized reading' (duration: 569.076175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.727033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.156654Z","time spent":"570.367976ms","remote":"127.0.0.1:60110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-10T00:52:16.817983Z","caller":"traceutil/trace.go:171","msg":"trace[1326472027] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"337.239948ms","start":"2026-05-10T00:52:16.480703Z","end":"2026-05-10T00:52:16.817943Z","steps":["trace[1326472027] 'process raft request' (duration: 337.053124ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:16.818008Z","caller":"traceutil/trace.go:171","msg":"trace[1645067285] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"339.102864ms","start":"2026-05-10T00:52:16.478875Z","end":"2026-05-10T00:52:16.817978Z","steps":["trace[1645067285] 'process raft request' (duration: 336.459989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.818094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.182544ms","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-10T00:52:16.818194Z","caller":"traceutil/trace.go:171","msg":"trace[1903462054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4816; }","duration":"150.318267ms","start":"2026-05-10T00:52:16.66786Z","end":"2026-05-10T00:52:16.818179Z","steps":["trace[1903462054] 'agreement among raft nodes before linearized reading' (duration: 150.114933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:16.818217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.478859Z","time spent":"339.230737ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:16.818238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:16.480687Z","time spent":"337.390082ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:21.833955Z","caller":"traceutil/trace.go:171","msg":"trace[1306975146] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5065; }","duration":"149.611999ms","start":"2026-05-10T00:52:21.684322Z","end":"2026-05-10T00:52:21.833934Z","steps":["trace[1306975146] 'read index received' (duration: 149.414745ms)","trace[1306975146] 'applied index is now lower than readState.Index' (duration: 196.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:21.834036Z","caller":"traceutil/trace.go:171","msg":"trace[378053150] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"257.894419ms","start":"2026-05-10T00:52:21.576109Z","end":"2026-05-10T00:52:21.834003Z","steps":["trace[378053150] 'process raft request' (duration: 257.702514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:21.834097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.753203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:21.834269Z","caller":"traceutil/trace.go:171","msg":"trace[2015440801] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4883; }","duration":"149.926577ms","start":"2026-05-10T00:52:21.684318Z","end":"2026-05-10T00:52:21.834244Z","steps":["trace[2015440801] 'agreement among raft nodes before linearized reading' (duration: 149.726632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.204417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.601584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-10T00:52:22.20451Z","caller":"traceutil/trace.go:171","msg":"trace[728620220] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4883; }","duration":"178.746786ms","start":"2026-05-10T00:52:22.025747Z","end":"2026-05-10T00:52:22.204494Z","steps":["trace[728620220] 'range keys from in-memory index tree' (duration: 178.420079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:26.901647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.607629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:52:26.901713Z","caller":"traceutil/trace.go:171","msg":"trace[13432362] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"148.528284ms","start":"2026-05-10T00:52:26.753155Z","end":"2026-05-10T00:52:26.901683Z","steps":["trace[13432362] 'process raft request' (duration: 98.055063ms)","trace[13432362] 'compare' (duration: 50.170674ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:26.901736Z","caller":"traceutil/trace.go:171","msg":"trace[1541482628] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4912; }","duration":"284.747063ms","start":"2026-05-10T00:52:26.61697Z","end":"2026-05-10T00:52:26.901717Z","steps":["trace[1541482628] 'count revisions from in-memory index tree' (duration: 284.502266ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:26.954525Z","caller":"traceutil/trace.go:171","msg":"trace[884289723] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"106.773552ms","start":"2026-05-10T00:52:26.847727Z","end":"2026-05-10T00:52:26.9545Z","steps":["trace[884289723] 'process raft request' (duration: 106.642068ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:26.954994Z","caller":"traceutil/trace.go:171","msg":"trace[606877968] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5098; }","duration":"100.287586ms","start":"2026-05-10T00:52:26.854693Z","end":"2026-05-10T00:52:26.95498Z","steps":["trace[606877968] 'read index received' (duration: 100.281446ms)","trace[606877968] 'applied index is now lower than readState.Index' (duration: 4.89µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:26.955209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.508581ms","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-10T00:52:26.955251Z","caller":"traceutil/trace.go:171","msg":"trace[492466965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4914; }","duration":"100.602874ms","start":"2026-05-10T00:52:26.854637Z","end":"2026-05-10T00:52:26.95524Z","steps":["trace[492466965] 'agreement among raft nodes before linearized reading' (duration: 100.42472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:26.969542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.450594ms","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-10T00:52:26.969616Z","caller":"traceutil/trace.go:171","msg":"trace[1269602531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4915; }","duration":"111.556627ms","start":"2026-05-10T00:52:26.858043Z","end":"2026-05-10T00:52:26.9696Z","steps":["trace[1269602531] 'agreement among raft nodes before linearized reading' (duration: 111.344282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:26.969836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.930352ms","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-10T00:52:26.969903Z","caller":"traceutil/trace.go:171","msg":"trace[978830473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4915; }","duration":"101.205697ms","start":"2026-05-10T00:52:26.868686Z","end":"2026-05-10T00:52:26.969891Z","steps":["trace[978830473] 'agreement among raft nodes before linearized reading' (duration: 100.927631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.571615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.923341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-10T00:52:27.571677Z","caller":"traceutil/trace.go:171","msg":"trace[990364823] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4921; }","duration":"276.028344ms","start":"2026-05-10T00:52:27.295635Z","end":"2026-05-10T00:52:27.571663Z","steps":["trace[990364823] 'range keys from in-memory index tree' (duration: 275.765787ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:54.019797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2899} {"level":"info","ts":"2026-05-10T00:53:54.093435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2899,"took":"71.882408ms","hash":3805810775,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":29347840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-10T00:53:54.093546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3805810775,"revision":2899,"compact-revision":-1} {"level":"info","ts":"2026-05-10T00:55:57.879593Z","caller":"traceutil/trace.go:171","msg":"trace[1652116702] transaction","detail":"{read_only:false; response_revision:6450; number_of_response:1; }","duration":"184.282107ms","start":"2026-05-10T00:55:57.695283Z","end":"2026-05-10T00:55:57.879566Z","steps":["trace[1652116702] 'process raft request' (duration: 184.128994ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:57.906299Z","caller":"traceutil/trace.go:171","msg":"trace[1296118882] transaction","detail":"{read_only:false; response_revision:6451; number_of_response:1; }","duration":"206.385629ms","start":"2026-05-10T00:55:57.699878Z","end":"2026-05-10T00:55:57.906264Z","steps":["trace[1296118882] 'process raft request' (duration: 206.054292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:04.330845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.980591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:56:04.331198Z","caller":"traceutil/trace.go:171","msg":"trace[1910876104] linearizableReadLoop","detail":"{readStateIndex:6723; appliedIndex:6722; }","duration":"249.400687ms","start":"2026-05-10T00:56:04.081775Z","end":"2026-05-10T00:56:04.331175Z","steps":["trace[1910876104] 'read index received' (duration: 45.042689ms)","trace[1910876104] 'applied index is now lower than readState.Index' (duration: 204.356718ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:56:04.33133Z","caller":"traceutil/trace.go:171","msg":"trace[417538174] transaction","detail":"{read_only:false; response_revision:6485; number_of_response:1; }","duration":"250.632652ms","start":"2026-05-10T00:56:04.080666Z","end":"2026-05-10T00:56:04.331298Z","steps":["trace[417538174] 'process raft request' (duration: 46.10052ms)","trace[417538174] 'compare' (duration: 203.863609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:56:04.331528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.725634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-10T00:56:04.331893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.581882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T00:56:04.331952Z","caller":"traceutil/trace.go:171","msg":"trace[714909144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6485; }","duration":"242.662973ms","start":"2026-05-10T00:56:04.089275Z","end":"2026-05-10T00:56:04.331938Z","steps":["trace[714909144] 'agreement among raft nodes before linearized reading' (duration: 242.447288ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:04.331776Z","caller":"traceutil/trace.go:171","msg":"trace[466290444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6485; }","duration":"249.974218ms","start":"2026-05-10T00:56:04.081758Z","end":"2026-05-10T00:56:04.331732Z","steps":["trace[466290444] 'agreement among raft nodes before linearized reading' (duration: 249.52972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:04.332174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.558402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:56:04.33222Z","caller":"traceutil/trace.go:171","msg":"trace[1658079336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6485; }","duration":"198.666455ms","start":"2026-05-10T00:56:04.133541Z","end":"2026-05-10T00:56:04.332208Z","steps":["trace[1658079336] 'agreement among raft nodes before linearized reading' (duration: 198.574043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:04.332474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.763075ms","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-10T00:56:04.332526Z","caller":"traceutil/trace.go:171","msg":"trace[1602664048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6485; }","duration":"244.839297ms","start":"2026-05-10T00:56:04.087674Z","end":"2026-05-10T00:56:04.332513Z","steps":["trace[1602664048] 'agreement among raft nodes before linearized reading' (duration: 244.597011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:04.332752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.265865ms","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-10T00:56:04.332825Z","caller":"traceutil/trace.go:171","msg":"trace[653010157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6485; }","duration":"245.359737ms","start":"2026-05-10T00:56:04.087448Z","end":"2026-05-10T00:56:04.332807Z","steps":["trace[653010157] 'agreement among raft nodes before linearized reading' (duration: 244.187513ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:05.413927Z","caller":"traceutil/trace.go:171","msg":"trace[1557333245] transaction","detail":"{read_only:false; response_revision:6492; number_of_response:1; }","duration":"137.217697ms","start":"2026-05-10T00:56:05.276676Z","end":"2026-05-10T00:56:05.413894Z","steps":["trace[1557333245] 'process raft request' (duration: 116.137136ms)","trace[1557333245] 'compare' (duration: 20.929327ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:58:54.032002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5603} {"level":"info","ts":"2026-05-10T00:58:54.10707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5603,"took":"74.006426ms","hash":866284172,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":18161664,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-10T00:58:54.107114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866284172,"revision":5603,"compact-revision":2899}