{"level":"warn","ts":"2026-07-25T16:20:37.611515Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T16:20:37.611609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-25T16:20:37.611694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T16:20:37.611707Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-25T16:20:37.611727Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T16:20:37.612324Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-25T16:20:37.612395Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-25T16:20:37.615585Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.969841ms"} {"level":"info","ts":"2026-07-25T16:20:37.621049Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-25T16:20:37.621128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T16:20:37.621252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-25T16:20:37.62127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T16:20:37.621279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-25T16:20:37.621373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-25T16:20:37.627231Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T16:20:37.628565Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T16:20:37.629646Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-25T16:20:37.631608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T16:20:37.631814Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T16:20:37.632336Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T16:20:37.632372Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T16:20:37.63187Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T16:20:37.633837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-25T16:20:37.634046Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-25T16:20:37.635488Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T16:20:37.635674Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-25T16:20:37.635715Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-25T16:20:37.63592Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T16:20:37.635954Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T16:20:38.422173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T16:20:38.42224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T16:20:38.422265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-25T16:20:38.422276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-25T16:20:38.422282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-25T16:20:38.422292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-25T16:20:38.4223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-25T16:20:38.424905Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T16:20:38.425026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T16:20:38.425082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T16:20:38.425126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T16:20:38.425169Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T16:20:38.425275Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:20:38.426328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:20:38.426418Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:20:38.42645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:20:38.427125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T16:20:38.427667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-07-25T16:20:40.218421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.754583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-25T16:20:40.218607Z","caller":"traceutil/trace.go:171","msg":"trace[1283284925] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"453.802539ms","start":"2026-07-25T16:20:39.764785Z","end":"2026-07-25T16:20:40.218587Z","steps":["trace[1283284925] 'read index received' (duration: 204.287081ms)","trace[1283284925] 'applied index is now lower than readState.Index' (duration: 249.514358ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:20:40.21867Z","caller":"traceutil/trace.go:171","msg":"trace[1163189484] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"464.538175ms","start":"2026-07-25T16:20:39.754069Z","end":"2026-07-25T16:20:40.218607Z","steps":["trace[1163189484] 'process raft request' (duration: 214.987126ms)","trace[1163189484] 'compare' (duration: 248.621549ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:20:40.218775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.971474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.218844Z","caller":"traceutil/trace.go:171","msg":"trace[885774048] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"454.065617ms","start":"2026-07-25T16:20:39.764757Z","end":"2026-07-25T16:20:40.218823Z","steps":["trace[885774048] 'agreement among raft nodes before linearized reading' (duration: 453.909602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.218907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.764749Z","time spent":"454.14466ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-25T16:20:40.21894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.754056Z","time spent":"464.724212ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:20:40.245653Z","caller":"traceutil/trace.go:171","msg":"trace[1024400700] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"449.775634ms","start":"2026-07-25T16:20:39.795853Z","end":"2026-07-25T16:20:40.245628Z","steps":["trace[1024400700] 'process raft request' (duration: 449.66722ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.245708Z","caller":"traceutil/trace.go:171","msg":"trace[773938843] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"449.825766ms","start":"2026-07-25T16:20:39.795868Z","end":"2026-07-25T16:20:40.245694Z","steps":["trace[773938843] 'process raft request' (duration: 449.679111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.245744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795834Z","time spent":"449.874447ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T16:20:40.245907Z","caller":"traceutil/trace.go:171","msg":"trace[1264474996] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"450.107764ms","start":"2026-07-25T16:20:39.795787Z","end":"2026-07-25T16:20:40.245895Z","steps":["trace[1264474996] 'process raft request' (duration: 449.689011ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.245649Z","caller":"traceutil/trace.go:171","msg":"trace[599654129] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"450.54762ms","start":"2026-07-25T16:20:39.79505Z","end":"2026-07-25T16:20:40.245598Z","steps":["trace[599654129] 'process raft request' (duration: 450.358564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.245993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795767Z","time spent":"450.172307ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T16:20:40.246036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795025Z","time spent":"450.975454ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T16:20:40.246102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.543166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.246158Z","caller":"traceutil/trace.go:171","msg":"trace[1738167892] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"450.516988ms","start":"2026-07-25T16:20:39.795633Z","end":"2026-07-25T16:20:40.24615Z","steps":["trace[1738167892] 'process raft request' (duration: 449.812676ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.246177Z","caller":"traceutil/trace.go:171","msg":"trace[604710466] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"452.64875ms","start":"2026-07-25T16:20:39.793508Z","end":"2026-07-25T16:20:40.246157Z","steps":["trace[604710466] 'agreement among raft nodes before linearized reading' (duration: 452.517695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.246216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.793487Z","time spent":"452.720771ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-25T16:20:40.246229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795612Z","time spent":"450.580221ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T16:20:40.246332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.457199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.246368Z","caller":"traceutil/trace.go:171","msg":"trace[1206525195] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"451.508311ms","start":"2026-07-25T16:20:39.794849Z","end":"2026-07-25T16:20:40.246357Z","steps":["trace[1206525195] 'agreement among raft nodes before linearized reading' (duration: 451.458459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.246396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.794839Z","time spent":"451.549952ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-25T16:20:40.246043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.75433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.246393Z","caller":"traceutil/trace.go:171","msg":"trace[650289032] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"444.638922ms","start":"2026-07-25T16:20:39.801736Z","end":"2026-07-25T16:20:40.246374Z","steps":["trace[650289032] 'process raft request' (duration: 443.838786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.247017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.801729Z","time spent":"445.227752ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T16:20:40.246532Z","caller":"traceutil/trace.go:171","msg":"trace[1929007315] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"440.263237ms","start":"2026-07-25T16:20:39.806259Z","end":"2026-07-25T16:20:40.246522Z","steps":["trace[1929007315] 'agreement among raft nodes before linearized reading' (duration: 439.597765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.247383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.80625Z","time spent":"441.049792ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-25T16:20:40.246594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.186487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.247807Z","caller":"traceutil/trace.go:171","msg":"trace[1578152724] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"381.410087ms","start":"2026-07-25T16:20:39.866381Z","end":"2026-07-25T16:20:40.247791Z","steps":["trace[1578152724] 'agreement among raft nodes before linearized reading' (duration: 380.190498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.247939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.866372Z","time spent":"381.550313ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-25T16:20:40.246683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.541328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.24826Z","caller":"traceutil/trace.go:171","msg":"trace[866647606] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"403.12793ms","start":"2026-07-25T16:20:39.845116Z","end":"2026-07-25T16:20:40.248244Z","steps":["trace[866647606] 'agreement among raft nodes before linearized reading' (duration: 401.541558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.248321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.845107Z","time spent":"403.199283ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-25T16:20:40.246713Z","caller":"traceutil/trace.go:171","msg":"trace[1286678541] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"440.639389ms","start":"2026-07-25T16:20:39.806063Z","end":"2026-07-25T16:20:40.246702Z","steps":["trace[1286678541] 'process raft request' (duration: 439.562034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.248633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.80605Z","time spent":"442.362916ms","remote":"127.0.0.1:58344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T16:20:40.246702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.404062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.248903Z","caller":"traceutil/trace.go:171","msg":"trace[20097300] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"442.674686ms","start":"2026-07-25T16:20:39.806201Z","end":"2026-07-25T16:20:40.248875Z","steps":["trace[20097300] 'agreement among raft nodes before linearized reading' (duration: 440.260686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.249084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.806193Z","time spent":"442.875703ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-25T16:20:40.246748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.262874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-25T16:20:40.246796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795855Z","time spent":"449.896898ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T16:20:40.245504Z","caller":"traceutil/trace.go:171","msg":"trace[926993266] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"450.448087ms","start":"2026-07-25T16:20:39.795032Z","end":"2026-07-25T16:20:40.24548Z","steps":["trace[926993266] 'process raft request' (duration: 450.276281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.249693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.795017Z","time spent":"454.599793ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T16:20:40.250134Z","caller":"traceutil/trace.go:171","msg":"trace[1706814191] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:0; response_revision:11; }","duration":"457.657116ms","start":"2026-07-25T16:20:39.792463Z","end":"2026-07-25T16:20:40.25012Z","steps":["trace[1706814191] 'agreement among raft nodes before linearized reading' (duration: 454.260474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.250309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:20:39.792454Z","time spent":"457.840281ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-07-25T16:20:40.422856Z","caller":"traceutil/trace.go:171","msg":"trace[37987350] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"171.863186ms","start":"2026-07-25T16:20:40.250966Z","end":"2026-07-25T16:20:40.422829Z","steps":["trace[37987350] 'process raft request' (duration: 168.753294ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424152Z","caller":"traceutil/trace.go:171","msg":"trace[924705376] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"172.952463ms","start":"2026-07-25T16:20:40.25117Z","end":"2026-07-25T16:20:40.424122Z","steps":["trace[924705376] 'process raft request' (duration: 172.678704ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424198Z","caller":"traceutil/trace.go:171","msg":"trace[273597591] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"166.155169ms","start":"2026-07-25T16:20:40.258019Z","end":"2026-07-25T16:20:40.424174Z","steps":["trace[273597591] 'process raft request' (duration: 166.033345ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424257Z","caller":"traceutil/trace.go:171","msg":"trace[1315841088] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"166.643356ms","start":"2026-07-25T16:20:40.257593Z","end":"2026-07-25T16:20:40.424236Z","steps":["trace[1315841088] 'process raft request' (duration: 166.390677ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424469Z","caller":"traceutil/trace.go:171","msg":"trace[1555630745] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"166.385317ms","start":"2026-07-25T16:20:40.258072Z","end":"2026-07-25T16:20:40.424458Z","steps":["trace[1555630745] 'process raft request' (duration: 166.004563ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424566Z","caller":"traceutil/trace.go:171","msg":"trace[1190848228] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"166.437939ms","start":"2026-07-25T16:20:40.258117Z","end":"2026-07-25T16:20:40.424555Z","steps":["trace[1190848228] 'process raft request' (duration: 166.040615ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.42454Z","caller":"traceutil/trace.go:171","msg":"trace[1595585137] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"166.434739ms","start":"2026-07-25T16:20:40.258095Z","end":"2026-07-25T16:20:40.424529Z","steps":["trace[1595585137] 'process raft request' (duration: 166.036715ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424718Z","caller":"traceutil/trace.go:171","msg":"trace[1267336482] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"166.537642ms","start":"2026-07-25T16:20:40.258169Z","end":"2026-07-25T16:20:40.424707Z","steps":["trace[1267336482] 'process raft request' (duration: 166.409978ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424764Z","caller":"traceutil/trace.go:171","msg":"trace[1883249096] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"172.014162ms","start":"2026-07-25T16:20:40.252686Z","end":"2026-07-25T16:20:40.424701Z","steps":["trace[1883249096] 'read index received' (duration: 167.045009ms)","trace[1883249096] 'applied index is now lower than readState.Index' (duration: 4.968343ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:20:40.424871Z","caller":"traceutil/trace.go:171","msg":"trace[928495968] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"166.711128ms","start":"2026-07-25T16:20:40.258148Z","end":"2026-07-25T16:20:40.42486Z","steps":["trace[928495968] 'process raft request' (duration: 166.029035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.424999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.296281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-mtmp6\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-07-25T16:20:40.425071Z","caller":"traceutil/trace.go:171","msg":"trace[1653983517] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-mtmp6; range_end:; response_count:1; response_revision:20; }","duration":"172.400425ms","start":"2026-07-25T16:20:40.252658Z","end":"2026-07-25T16:20:40.425059Z","steps":["trace[1653983517] 'agreement among raft nodes before linearized reading' (duration: 172.288081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.425107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.358038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T16:20:40.425158Z","caller":"traceutil/trace.go:171","msg":"trace[924163677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"167.431761ms","start":"2026-07-25T16:20:40.257711Z","end":"2026-07-25T16:20:40.425143Z","steps":["trace[924163677] 'agreement among raft nodes before linearized reading' (duration: 167.347098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:20:40.425313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.099528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-25T16:20:40.425346Z","caller":"traceutil/trace.go:171","msg":"trace[1880778769] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"108.16918ms","start":"2026-07-25T16:20:40.317169Z","end":"2026-07-25T16:20:40.425338Z","steps":["trace[1880778769] 'agreement among raft nodes before linearized reading' (duration: 108.119068ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:20:40.424874Z","caller":"traceutil/trace.go:171","msg":"trace[387767809] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"161.241358ms","start":"2026-07-25T16:20:40.263623Z","end":"2026-07-25T16:20:40.424864Z","steps":["trace[387767809] 'process raft request' (duration: 161.030641ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:19.033914Z","caller":"traceutil/trace.go:171","msg":"trace[32655823] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"151.573034ms","start":"2026-07-25T16:21:18.882324Z","end":"2026-07-25T16:21:19.033897Z","steps":["trace[32655823] 'read index received' (duration: 151.429031ms)","trace[32655823] 'applied index is now lower than readState.Index' (duration: 143.213µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:19.034045Z","caller":"traceutil/trace.go:171","msg":"trace[957082899] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"266.830845ms","start":"2026-07-25T16:21:18.767205Z","end":"2026-07-25T16:21:19.034036Z","steps":["trace[957082899] 'process raft request' (duration: 266.585569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:19.03416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.820129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:19.03418Z","caller":"traceutil/trace.go:171","msg":"trace[1364025882] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:569; }","duration":"151.888661ms","start":"2026-07-25T16:21:18.882285Z","end":"2026-07-25T16:21:19.034173Z","steps":["trace[1364025882] 'agreement among raft nodes before linearized reading' (duration: 151.8367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:19.034345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.000073ms","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-07-25T16:21:19.034366Z","caller":"traceutil/trace.go:171","msg":"trace[226987972] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:569; }","duration":"152.051524ms","start":"2026-07-25T16:21:18.882308Z","end":"2026-07-25T16:21:19.03436Z","steps":["trace[226987972] 'agreement among raft nodes before linearized reading' (duration: 151.984352ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:22.591804Z","caller":"traceutil/trace.go:171","msg":"trace[1484775605] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"175.653468ms","start":"2026-07-25T16:21:22.416101Z","end":"2026-07-25T16:21:22.591755Z","steps":["trace[1484775605] 'process raft request' (duration: 175.434383ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:23.143001Z","caller":"traceutil/trace.go:171","msg":"trace[1741876484] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"413.816404ms","start":"2026-07-25T16:21:22.729141Z","end":"2026-07-25T16:21:23.142957Z","steps":["trace[1741876484] 'process raft request' (duration: 412.631579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:23.143212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:22.729104Z","time spent":"413.996448ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:23.471238Z","caller":"traceutil/trace.go:171","msg":"trace[593915623] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"261.475075ms","start":"2026-07-25T16:21:23.209729Z","end":"2026-07-25T16:21:23.471204Z","steps":["trace[593915623] 'process raft request' (duration: 234.185244ms)","trace[593915623] 'compare' (duration: 27.121348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:38.931092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515075432708970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T16:21:39.322317Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045818994s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T16:21:39.322714Z","caller":"traceutil/trace.go:171","msg":"trace[1162121920] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"891.703796ms","start":"2026-07-25T16:21:38.430978Z","end":"2026-07-25T16:21:39.322682Z","steps":["trace[1162121920] 'read index received' (duration: 891.482071ms)","trace[1162121920] 'applied index is now lower than readState.Index' (duration: 220.555µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:39.322754Z","caller":"traceutil/trace.go:171","msg":"trace[440284801] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.046357105s","start":"2026-07-25T16:21:38.276364Z","end":"2026-07-25T16:21:39.322721Z","steps":["trace[440284801] 'process raft request' (duration: 1.046180361s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.322934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.949831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T16:21:39.32294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.276343Z","time spent":"1.046506387s","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.32298Z","caller":"traceutil/trace.go:171","msg":"trace[1968979580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"892.036302ms","start":"2026-07-25T16:21:38.430923Z","end":"2026-07-25T16:21:39.32296Z","steps":["trace[1968979580] 'agreement among raft nodes before linearized reading' (duration: 891.877539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.323012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.430899Z","time spent":"892.105144ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T16:21:39.323019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.878627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fwmvt\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-25T16:21:39.323073Z","caller":"traceutil/trace.go:171","msg":"trace[391900740] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fwmvt; range_end:; response_count:1; response_revision:757; }","duration":"861.962248ms","start":"2026-07-25T16:21:38.46109Z","end":"2026-07-25T16:21:39.323052Z","steps":["trace[391900740] 'agreement among raft nodes before linearized reading' (duration: 861.811765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.32311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.461081Z","time spent":"862.021899ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fwmvt\" "} {"level":"warn","ts":"2026-07-25T16:21:39.323154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.566139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T16:21:39.323187Z","caller":"traceutil/trace.go:171","msg":"trace[1436678368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"471.626309ms","start":"2026-07-25T16:21:38.851551Z","end":"2026-07-25T16:21:39.323177Z","steps":["trace[1436678368] 'agreement among raft nodes before linearized reading' (duration: 471.543818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.323212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.851534Z","time spent":"471.67291ms","remote":"127.0.0.1:58462","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":"warn","ts":"2026-07-25T16:21:39.323415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.074418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:39.323449Z","caller":"traceutil/trace.go:171","msg":"trace[552081131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"247.19251ms","start":"2026-07-25T16:21:39.076248Z","end":"2026-07-25T16:21:39.323441Z","steps":["trace[552081131] 'agreement among raft nodes before linearized reading' (duration: 247.144649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.323417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.353694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T16:21:39.32351Z","caller":"traceutil/trace.go:171","msg":"trace[1247591356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"596.479236ms","start":"2026-07-25T16:21:38.727011Z","end":"2026-07-25T16:21:39.32349Z","steps":["trace[1247591356] 'agreement among raft nodes before linearized reading' (duration: 596.17101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.323561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.727001Z","time spent":"596.551158ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T16:21:39.323653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.265814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:39.325582Z","caller":"traceutil/trace.go:171","msg":"trace[729990093] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:757; }","duration":"686.474678ms","start":"2026-07-25T16:21:38.637244Z","end":"2026-07-25T16:21:39.323719Z","steps":["trace[729990093] 'agreement among raft nodes before linearized reading' (duration: 686.03436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.325718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.637227Z","time spent":"688.455305ms","remote":"127.0.0.1:58316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-25T16:21:39.675114Z","caller":"traceutil/trace.go:171","msg":"trace[460011062] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"346.264679ms","start":"2026-07-25T16:21:39.32882Z","end":"2026-07-25T16:21:39.675084Z","steps":["trace[460011062] 'process raft request' (duration: 346.094876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.675454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:39.328808Z","time spent":"346.389341ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.680802Z","caller":"traceutil/trace.go:171","msg":"trace[72166908] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"350.734002ms","start":"2026-07-25T16:21:39.330028Z","end":"2026-07-25T16:21:39.680762Z","steps":["trace[72166908] 'process raft request' (duration: 350.288634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.681048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:39.330005Z","time spent":"350.911674ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.68116Z","caller":"traceutil/trace.go:171","msg":"trace[617849815] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"351.106589ms","start":"2026-07-25T16:21:39.330028Z","end":"2026-07-25T16:21:39.681135Z","steps":["trace[617849815] 'process raft request' (duration: 350.63109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.681276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:39.330002Z","time spent":"351.216111ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.681266Z","caller":"traceutil/trace.go:171","msg":"trace[1021993638] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"348.745025ms","start":"2026-07-25T16:21:39.332491Z","end":"2026-07-25T16:21:39.681236Z","steps":["trace[1021993638] 'process raft request' (duration: 348.685154ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:39.681354Z","caller":"traceutil/trace.go:171","msg":"trace[1122537788] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"350.949686ms","start":"2026-07-25T16:21:39.330394Z","end":"2026-07-25T16:21:39.681343Z","steps":["trace[1122537788] 'process raft request' (duration: 350.6583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.681411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:39.330381Z","time spent":"351.001578ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:39.681434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:39.332483Z","time spent":"348.861957ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:42.715988Z","caller":"traceutil/trace.go:171","msg":"trace[1637024276] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"229.070315ms","start":"2026-07-25T16:21:42.486894Z","end":"2026-07-25T16:21:42.715964Z","steps":["trace[1637024276] 'process raft request' (duration: 228.942982ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:51.577147Z","caller":"traceutil/trace.go:171","msg":"trace[1627379269] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"121.465661ms","start":"2026-07-25T16:21:51.455651Z","end":"2026-07-25T16:21:51.577117Z","steps":["trace[1627379269] 'process raft request' (duration: 121.274568ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:53.266001Z","caller":"traceutil/trace.go:171","msg":"trace[1269066116] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"263.980327ms","start":"2026-07-25T16:21:53.001989Z","end":"2026-07-25T16:21:53.265969Z","steps":["trace[1269066116] 'process raft request' (duration: 263.807954ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:10.116667Z","caller":"traceutil/trace.go:171","msg":"trace[804115118] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"132.570538ms","start":"2026-07-25T16:22:09.984077Z","end":"2026-07-25T16:22:10.116648Z","steps":["trace[804115118] 'process raft request' (duration: 126.553698ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:10.117485Z","caller":"traceutil/trace.go:171","msg":"trace[665941977] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"131.887187ms","start":"2026-07-25T16:22:09.98555Z","end":"2026-07-25T16:22:10.117437Z","steps":["trace[665941977] 'process raft request' (duration: 131.829907ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:10.117711Z","caller":"traceutil/trace.go:171","msg":"trace[1368842249] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"133.241377ms","start":"2026-07-25T16:22:09.984449Z","end":"2026-07-25T16:22:10.117691Z","steps":["trace[1368842249] 'process raft request' (duration: 132.7479ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:36.29308Z","caller":"traceutil/trace.go:171","msg":"trace[425225518] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"192.993578ms","start":"2026-07-25T16:22:36.100056Z","end":"2026-07-25T16:22:36.293049Z","steps":["trace[425225518] 'process raft request' (duration: 192.803268ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:37.003009Z","caller":"traceutil/trace.go:171","msg":"trace[1897784413] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"706.453797ms","start":"2026-07-25T16:22:36.296529Z","end":"2026-07-25T16:22:37.002983Z","steps":["trace[1897784413] 'process raft request' (duration: 706.305166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:37.003163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:36.29651Z","time spent":"706.568566ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:22:37.004584Z","caller":"traceutil/trace.go:171","msg":"trace[183030110] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"707.631396ms","start":"2026-07-25T16:22:36.296936Z","end":"2026-07-25T16:22:37.004568Z","steps":["trace[183030110] 'process raft request' (duration: 707.377175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:37.004662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:36.296909Z","time spent":"707.715076ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:22:37.00503Z","caller":"traceutil/trace.go:171","msg":"trace[1591153672] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"708.066405ms","start":"2026-07-25T16:22:36.296945Z","end":"2026-07-25T16:22:37.005012Z","steps":["trace[1591153672] 'process raft request' (duration: 707.305265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:37.005165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:36.296937Z","time spent":"708.166925ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:22:37.007444Z","caller":"traceutil/trace.go:171","msg":"trace[310527609] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1085; }","duration":"365.185947ms","start":"2026-07-25T16:22:36.642231Z","end":"2026-07-25T16:22:37.007417Z","steps":["trace[310527609] 'read index received' (duration: 360.576481ms)","trace[310527609] 'applied index is now lower than readState.Index' (duration: 4.608116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:22:37.00755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.296037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:22:37.007608Z","caller":"traceutil/trace.go:171","msg":"trace[472470871] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1046; }","duration":"365.406557ms","start":"2026-07-25T16:22:36.642188Z","end":"2026-07-25T16:22:37.007595Z","steps":["trace[472470871] 'agreement among raft nodes before linearized reading' (duration: 365.295167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:37.007643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:36.642146Z","time spent":"365.490676ms","remote":"127.0.0.1:58486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-25T16:22:58.242261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515075432709799,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T16:22:58.442038Z","caller":"traceutil/trace.go:171","msg":"trace[167838836] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"700.458534ms","start":"2026-07-25T16:22:57.741556Z","end":"2026-07-25T16:22:58.442015Z","steps":["trace[167838836] 'read index received' (duration: 700.268003ms)","trace[167838836] 'applied index is now lower than readState.Index' (duration: 189.661µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:22:58.44208Z","caller":"traceutil/trace.go:171","msg":"trace[777968147] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"913.889009ms","start":"2026-07-25T16:22:57.528162Z","end":"2026-07-25T16:22:58.442051Z","steps":["trace[777968147] 'process raft request' (duration: 913.714347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:58.442244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:57.528135Z","time spent":"914.02081ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:22:58.442726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.15636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T16:22:58.44279Z","caller":"traceutil/trace.go:171","msg":"trace[976017440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"701.260362ms","start":"2026-07-25T16:22:57.741503Z","end":"2026-07-25T16:22:58.442764Z","steps":["trace[976017440] 'agreement among raft nodes before linearized reading' (duration: 700.650807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:58.442822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:57.74147Z","time spent":"701.343822ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T16:22:58.442499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.505554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:22:58.443113Z","caller":"traceutil/trace.go:171","msg":"trace[797150387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"367.263192ms","start":"2026-07-25T16:22:58.075825Z","end":"2026-07-25T16:22:58.443089Z","steps":["trace[797150387] 'agreement among raft nodes before linearized reading' (duration: 366.418713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:58.443156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:22:58.07581Z","time spent":"367.331472ms","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T16:22:58.592533Z","caller":"traceutil/trace.go:171","msg":"trace[1430556113] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"145.457249ms","start":"2026-07-25T16:22:58.447051Z","end":"2026-07-25T16:22:58.592508Z","steps":["trace[1430556113] 'process raft request' (duration: 145.405438ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:58.592828Z","caller":"traceutil/trace.go:171","msg":"trace[1228475526] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"146.321798ms","start":"2026-07-25T16:22:58.446489Z","end":"2026-07-25T16:22:58.592811Z","steps":["trace[1228475526] 'process raft request' (duration: 102.687199ms)","trace[1228475526] 'compare' (duration: 43.143623ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:23:01.096479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515075432709817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T16:23:01.11666Z","caller":"traceutil/trace.go:171","msg":"trace[605565519] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"520.716685ms","start":"2026-07-25T16:23:00.595924Z","end":"2026-07-25T16:23:01.116641Z","steps":["trace[605565519] 'read index received' (duration: 520.643234ms)","trace[605565519] 'applied index is now lower than readState.Index' (duration: 72.721µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:23:01.116877Z","caller":"traceutil/trace.go:171","msg":"trace[166800479] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"669.119262ms","start":"2026-07-25T16:23:00.447749Z","end":"2026-07-25T16:23:01.116868Z","steps":["trace[166800479] 'process raft request' (duration: 668.777869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:23:01.116976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:23:00.447731Z","time spent":"669.174872ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:23:01.117055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.623563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T16:23:01.117078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.141608ms","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-07-25T16:23:01.117127Z","caller":"traceutil/trace.go:171","msg":"trace[780062211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1119; }","duration":"520.731114ms","start":"2026-07-25T16:23:00.596378Z","end":"2026-07-25T16:23:01.11711Z","steps":["trace[780062211] 'agreement among raft nodes before linearized reading' (duration: 520.492122ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:23:01.117154Z","caller":"traceutil/trace.go:171","msg":"trace[609030255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1119; }","duration":"521.27094ms","start":"2026-07-25T16:23:00.595863Z","end":"2026-07-25T16:23:01.117134Z","steps":["trace[609030255] 'agreement among raft nodes before linearized reading' (duration: 521.023978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:23:01.117176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:23:00.596372Z","time spent":"520.795594ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T16:23:01.117213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:23:00.595842Z","time spent":"521.361231ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T16:23:04.359281Z","caller":"traceutil/trace.go:171","msg":"trace[701094141] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"266.731411ms","start":"2026-07-25T16:23:04.092524Z","end":"2026-07-25T16:23:04.359256Z","steps":["trace[701094141] 'read index received' (duration: 266.508559ms)","trace[701094141] 'applied index is now lower than readState.Index' (duration: 221.712µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:23:04.359462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.920953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:23:04.359544Z","caller":"traceutil/trace.go:171","msg":"trace[1019280172] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1132; }","duration":"267.053304ms","start":"2026-07-25T16:23:04.092479Z","end":"2026-07-25T16:23:04.359532Z","steps":["trace[1019280172] 'agreement among raft nodes before linearized reading' (duration: 266.914363ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:23:04.359719Z","caller":"traceutil/trace.go:171","msg":"trace[320450603] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"280.027375ms","start":"2026-07-25T16:23:04.079649Z","end":"2026-07-25T16:23:04.359676Z","steps":["trace[320450603] 'process raft request' (duration: 279.43387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:23:04.702353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.585709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:23:04.702462Z","caller":"traceutil/trace.go:171","msg":"trace[1504866289] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1132; }","duration":"161.75847ms","start":"2026-07-25T16:23:04.540685Z","end":"2026-07-25T16:23:04.702444Z","steps":["trace[1504866289] 'count revisions from in-memory index tree' (duration: 161.500099ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:23:05.247561Z","caller":"traceutil/trace.go:171","msg":"trace[173707670] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"105.869708ms","start":"2026-07-25T16:23:05.14167Z","end":"2026-07-25T16:23:05.24754Z","steps":["trace[173707670] 'read index received' (duration: 105.748937ms)","trace[173707670] 'applied index is now lower than readState.Index' (duration: 119.781µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:23:05.24763Z","caller":"traceutil/trace.go:171","msg":"trace[610913952] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"118.256865ms","start":"2026-07-25T16:23:05.129347Z","end":"2026-07-25T16:23:05.247603Z","steps":["trace[610913952] 'process raft request' (duration: 118.056452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:23:05.247785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.094171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T16:23:05.247823Z","caller":"traceutil/trace.go:171","msg":"trace[32248836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1133; }","duration":"106.179052ms","start":"2026-07-25T16:23:05.141634Z","end":"2026-07-25T16:23:05.247813Z","steps":["trace[32248836] 'agreement among raft nodes before linearized reading' (duration: 106.034851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:27.737421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.978872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T16:24:27.737501Z","caller":"traceutil/trace.go:171","msg":"trace[1408291564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"101.078454ms","start":"2026-07-25T16:24:27.636402Z","end":"2026-07-25T16:24:27.737481Z","steps":["trace[1408291564] 'range keys from in-memory index tree' (duration: 100.851857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:27.738001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.00716ms","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-07-25T16:24:27.738084Z","caller":"traceutil/trace.go:171","msg":"trace[1849788660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"105.131544ms","start":"2026-07-25T16:24:27.632933Z","end":"2026-07-25T16:24:27.738065Z","steps":["trace[1849788660] 'range keys from in-memory index tree' (duration: 104.70609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.966377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T16:24:30.374465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.007731Z","time spent":"366.724912ms","remote":"127.0.0.1:35612","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-25T16:24:30.374544Z","caller":"traceutil/trace.go:171","msg":"trace[1834303595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1410; }","duration":"620.136463ms","start":"2026-07-25T16:24:29.754386Z","end":"2026-07-25T16:24:30.374523Z","steps":["trace[1834303595] 'range keys from in-memory index tree' (duration: 619.78859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.921862ms","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-07-25T16:24:30.374594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.754364Z","time spent":"620.219304ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T16:24:30.374599Z","caller":"traceutil/trace.go:171","msg":"trace[1185372100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1410; }","duration":"540.971794ms","start":"2026-07-25T16:24:29.833612Z","end":"2026-07-25T16:24:30.374584Z","steps":["trace[1185372100] 'range keys from in-memory index tree' (duration: 540.84819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.425101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T16:24:30.374638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.833603Z","time spent":"541.024026ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T16:24:30.37465Z","caller":"traceutil/trace.go:171","msg":"trace[187048225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1410; }","duration":"298.524424ms","start":"2026-07-25T16:24:30.07611Z","end":"2026-07-25T16:24:30.374634Z","steps":["trace[187048225] 'range keys from in-memory index tree' (duration: 298.366868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.743715ms","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-07-25T16:24:30.374755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.834021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T16:24:30.374774Z","caller":"traceutil/trace.go:171","msg":"trace[1647856785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1410; }","duration":"540.116987ms","start":"2026-07-25T16:24:29.834621Z","end":"2026-07-25T16:24:30.374738Z","steps":["trace[1647856785] 'range keys from in-memory index tree' (duration: 539.566179ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:30.374782Z","caller":"traceutil/trace.go:171","msg":"trace[753544965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1410; }","duration":"540.877262ms","start":"2026-07-25T16:24:29.833895Z","end":"2026-07-25T16:24:30.374772Z","steps":["trace[753544965] 'range keys from in-memory index tree' (duration: 540.766237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.833887Z","time spent":"540.923274ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T16:24:30.374839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.834612Z","time spent":"540.198761ms","remote":"127.0.0.1:58462","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":"warn","ts":"2026-07-25T16:24:30.37448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.869573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T16:24:30.374912Z","caller":"traceutil/trace.go:171","msg":"trace[1819123462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1410; }","duration":"542.318038ms","start":"2026-07-25T16:24:29.832582Z","end":"2026-07-25T16:24:30.3749Z","steps":["trace[1819123462] 'range keys from in-memory index tree' (duration: 541.786061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.374949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.832572Z","time spent":"542.36781ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T16:24:30.98031Z","caller":"traceutil/trace.go:171","msg":"trace[1416328151] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"600.91901ms","start":"2026-07-25T16:24:30.379357Z","end":"2026-07-25T16:24:30.980276Z","steps":["trace[1416328151] 'process raft request' (duration: 600.706514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.980485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.379345Z","time spent":"601.049424ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:31.094662Z","caller":"traceutil/trace.go:171","msg":"trace[1624865626] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"714.957978ms","start":"2026-07-25T16:24:30.379688Z","end":"2026-07-25T16:24:31.094646Z","steps":["trace[1624865626] 'process raft request' (duration: 714.844784ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:31.094658Z","caller":"traceutil/trace.go:171","msg":"trace[317608750] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"714.975699ms","start":"2026-07-25T16:24:30.379657Z","end":"2026-07-25T16:24:31.094633Z","steps":["trace[317608750] 'process raft request' (duration: 714.73012ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:31.09468Z","caller":"traceutil/trace.go:171","msg":"trace[1202237671] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"714.966018ms","start":"2026-07-25T16:24:30.37969Z","end":"2026-07-25T16:24:31.094656Z","steps":["trace[1202237671] 'process raft request' (duration: 714.887706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:31.09475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.379667Z","time spent":"715.03573ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:24:31.094788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.379648Z","time spent":"715.065181ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:24:31.094829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.379671Z","time spent":"715.080871ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:31.094868Z","caller":"traceutil/trace.go:171","msg":"trace[200768444] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"712.287913ms","start":"2026-07-25T16:24:30.382548Z","end":"2026-07-25T16:24:31.094836Z","steps":["trace[200768444] 'process raft request' (duration: 712.068636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:31.095001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.382537Z","time spent":"712.389766ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:45.271798Z","caller":"traceutil/trace.go:171","msg":"trace[1108010987] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"194.66693ms","start":"2026-07-25T16:24:45.077094Z","end":"2026-07-25T16:24:45.271761Z","steps":["trace[1108010987] 'read index received' (duration: 194.464886ms)","trace[1108010987] 'applied index is now lower than readState.Index' (duration: 200.684µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:24:45.271882Z","caller":"traceutil/trace.go:171","msg":"trace[1286513517] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"225.89912ms","start":"2026-07-25T16:24:45.045963Z","end":"2026-07-25T16:24:45.271862Z","steps":["trace[1286513517] 'process raft request' (duration: 225.628544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.272011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.879814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:24:45.272065Z","caller":"traceutil/trace.go:171","msg":"trace[644630636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1457; }","duration":"194.980546ms","start":"2026-07-25T16:24:45.077071Z","end":"2026-07-25T16:24:45.272051Z","steps":["trace[644630636] 'agreement among raft nodes before linearized reading' (duration: 194.849623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.272153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.108893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T16:24:45.272229Z","caller":"traceutil/trace.go:171","msg":"trace[1350107256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"114.245787ms","start":"2026-07-25T16:24:45.157965Z","end":"2026-07-25T16:24:45.272211Z","steps":["trace[1350107256] 'agreement among raft nodes before linearized reading' (duration: 114.079553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.272283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.485866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-25T16:24:45.272414Z","caller":"traceutil/trace.go:171","msg":"trace[1535362464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"108.650001ms","start":"2026-07-25T16:24:45.163743Z","end":"2026-07-25T16:24:45.272393Z","steps":["trace[1535362464] 'agreement among raft nodes before linearized reading' (duration: 108.323604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.272444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.510624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-25T16:24:45.272584Z","caller":"traceutil/trace.go:171","msg":"trace[1550071153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1457; }","duration":"115.741169ms","start":"2026-07-25T16:24:45.156819Z","end":"2026-07-25T16:24:45.27256Z","steps":["trace[1550071153] 'agreement among raft nodes before linearized reading' (duration: 115.315609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.273155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.587302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T16:24:45.27326Z","caller":"traceutil/trace.go:171","msg":"trace[1363145026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1457; }","duration":"124.730935ms","start":"2026-07-25T16:24:45.148517Z","end":"2026-07-25T16:24:45.273248Z","steps":["trace[1363145026] 'agreement among raft nodes before linearized reading' (duration: 123.525779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.760627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.513813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:24:45.760834Z","caller":"traceutil/trace.go:171","msg":"trace[353687536] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"483.737973ms","start":"2026-07-25T16:24:45.277071Z","end":"2026-07-25T16:24:45.760809Z","steps":["trace[353687536] 'process raft request' (duration: 329.987515ms)","trace[353687536] 'compare' (duration: 153.046344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:45.760943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:45.277052Z","time spent":"483.854876ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:45.804878Z","caller":"traceutil/trace.go:171","msg":"trace[1840318347] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"526.797569ms","start":"2026-07-25T16:24:45.278064Z","end":"2026-07-25T16:24:45.804862Z","steps":["trace[1840318347] 'process raft request' (duration: 526.747798ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:45.80498Z","caller":"traceutil/trace.go:171","msg":"trace[483414517] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"526.952652ms","start":"2026-07-25T16:24:45.278017Z","end":"2026-07-25T16:24:45.804969Z","steps":["trace[483414517] 'process raft request' (duration: 526.743288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.805138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:45.278056Z","time spent":"526.925471ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:24:45.805142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:45.27801Z","time spent":"527.030314ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:45.804895Z","caller":"traceutil/trace.go:171","msg":"trace[2029501291] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"526.909761ms","start":"2026-07-25T16:24:45.277944Z","end":"2026-07-25T16:24:45.804854Z","steps":["trace[2029501291] 'process raft request' (duration: 526.713687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:45.80588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:45.277933Z","time spent":"527.483352ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:45.812425Z","caller":"traceutil/trace.go:171","msg":"trace[1647715306] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"161.229354ms","start":"2026-07-25T16:24:45.65118Z","end":"2026-07-25T16:24:45.812409Z","steps":["trace[1647715306] 'process raft request' (duration: 161.090831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:47.08541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.043609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-25T16:29:29.055621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.666061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:29:29.055723Z","caller":"traceutil/trace.go:171","msg":"trace[1959084983] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2389; }","duration":"212.798914ms","start":"2026-07-25T16:29:28.842905Z","end":"2026-07-25T16:29:29.055704Z","steps":["trace[1959084983] 'count revisions from in-memory index tree' (duration: 212.551989ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.154559Z","caller":"traceutil/trace.go:171","msg":"trace[1939662919] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"307.835936ms","start":"2026-07-25T16:29:28.846693Z","end":"2026-07-25T16:29:29.154529Z","steps":["trace[1939662919] 'process raft request' (duration: 307.697944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:29:29.154737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:29:28.846679Z","time spent":"307.949678ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:29:29.4884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.258155ms","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-07-25T16:29:29.488543Z","caller":"traceutil/trace.go:171","msg":"trace[810987397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2390; }","duration":"272.44996ms","start":"2026-07-25T16:29:29.216072Z","end":"2026-07-25T16:29:29.488521Z","steps":["trace[810987397] 'range keys from in-memory index tree' (duration: 272.070541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:29:29.488613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.406117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T16:29:29.488697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.712885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T16:29:29.488766Z","caller":"traceutil/trace.go:171","msg":"trace[1420516359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2390; }","duration":"277.609622ms","start":"2026-07-25T16:29:29.211112Z","end":"2026-07-25T16:29:29.488722Z","steps":["trace[1420516359] 'range keys from in-memory index tree' (duration: 277.156462ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.4888Z","caller":"traceutil/trace.go:171","msg":"trace[1624414598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2390; }","duration":"270.858209ms","start":"2026-07-25T16:29:29.217917Z","end":"2026-07-25T16:29:29.488775Z","steps":["trace[1624414598] 'range keys from in-memory index tree' (duration: 270.526972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:29:29.488707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.323928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T16:29:29.488946Z","caller":"traceutil/trace.go:171","msg":"trace[843535640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2390; }","duration":"270.597043ms","start":"2026-07-25T16:29:29.218339Z","end":"2026-07-25T16:29:29.488936Z","steps":["trace[843535640] 'range keys from in-memory index tree' (duration: 270.187275ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.619395Z","caller":"traceutil/trace.go:171","msg":"trace[440288084] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"125.765869ms","start":"2026-07-25T16:29:29.493601Z","end":"2026-07-25T16:29:29.619367Z","steps":["trace[440288084] 'process raft request' (duration: 125.576215ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.640203Z","caller":"traceutil/trace.go:171","msg":"trace[1312276136] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"146.192261ms","start":"2026-07-25T16:29:29.493986Z","end":"2026-07-25T16:29:29.640178Z","steps":["trace[1312276136] 'process raft request' (duration: 146.102619ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.640368Z","caller":"traceutil/trace.go:171","msg":"trace[605543923] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"145.446466ms","start":"2026-07-25T16:29:29.494898Z","end":"2026-07-25T16:29:29.640345Z","steps":["trace[605543923] 'process raft request' (duration: 145.248463ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:29.640534Z","caller":"traceutil/trace.go:171","msg":"trace[642917388] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"146.869254ms","start":"2026-07-25T16:29:29.493631Z","end":"2026-07-25T16:29:29.6405Z","steps":["trace[642917388] 'process raft request' (duration: 146.339924ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:29:57.390561Z","caller":"traceutil/trace.go:171","msg":"trace[2066504389] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"125.204519ms","start":"2026-07-25T16:29:57.265329Z","end":"2026-07-25T16:29:57.390534Z","steps":["trace[2066504389] 'process raft request' (duration: 125.034695ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:30:15.056992Z","caller":"traceutil/trace.go:171","msg":"trace[1914414730] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"111.273868ms","start":"2026-07-25T16:30:14.94569Z","end":"2026-07-25T16:30:15.056964Z","steps":["trace[1914414730] 'process raft request' (duration: 111.080384ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:30:32.217276Z","caller":"traceutil/trace.go:171","msg":"trace[1732216282] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2807; }","duration":"277.66086ms","start":"2026-07-25T16:30:31.939591Z","end":"2026-07-25T16:30:32.217252Z","steps":["trace[1732216282] 'read index received' (duration: 277.521087ms)","trace[1732216282] 'applied index is now lower than readState.Index' (duration: 138.663µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:30:32.217371Z","caller":"traceutil/trace.go:171","msg":"trace[786767030] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"304.862531ms","start":"2026-07-25T16:30:31.912475Z","end":"2026-07-25T16:30:32.217337Z","steps":["trace[786767030] 'process raft request' (duration: 304.612435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:32.217542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:30:31.91246Z","time spent":"304.988433ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:30:32.217539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.996428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T16:30:32.217586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.892765ms","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-07-25T16:30:32.217614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.845332ms","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-07-25T16:30:32.217627Z","caller":"traceutil/trace.go:171","msg":"trace[1566351952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2668; }","duration":"142.1209ms","start":"2026-07-25T16:30:32.075484Z","end":"2026-07-25T16:30:32.217605Z","steps":["trace[1566351952] 'agreement among raft nodes before linearized reading' (duration: 141.948836ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:30:32.217668Z","caller":"traceutil/trace.go:171","msg":"trace[15262105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2668; }","duration":"242.922664ms","start":"2026-07-25T16:30:31.974735Z","end":"2026-07-25T16:30:32.217658Z","steps":["trace[15262105] 'agreement among raft nodes before linearized reading' (duration: 242.782581ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:30:32.21767Z","caller":"traceutil/trace.go:171","msg":"trace[2102698669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2668; }","duration":"278.016228ms","start":"2026-07-25T16:30:31.939637Z","end":"2026-07-25T16:30:32.217653Z","steps":["trace[2102698669] 'agreement among raft nodes before linearized reading' (duration: 277.784263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:32.217872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.278333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T16:30:32.217919Z","caller":"traceutil/trace.go:171","msg":"trace[1206468278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2668; }","duration":"278.362263ms","start":"2026-07-25T16:30:31.939546Z","end":"2026-07-25T16:30:32.217908Z","steps":["trace[1206468278] 'agreement among raft nodes before linearized reading' (duration: 277.831133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:32.553313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.803206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:30:32.553406Z","caller":"traceutil/trace.go:171","msg":"trace[493666409] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"331.637092ms","start":"2026-07-25T16:30:32.221749Z","end":"2026-07-25T16:30:32.553386Z","steps":["trace[493666409] 'process raft request' (duration: 100.686512ms)","trace[493666409] 'compare' (duration: 230.663774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:30:32.55346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:30:32.221732Z","time spent":"331.701763ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:30:32.756056Z","caller":"traceutil/trace.go:171","msg":"trace[274552964] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"533.931773ms","start":"2026-07-25T16:30:32.222098Z","end":"2026-07-25T16:30:32.756029Z","steps":["trace[274552964] 'process raft request' (duration: 501.135665ms)","trace[274552964] 'compare' (duration: 32.607614ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:30:32.756174Z","caller":"traceutil/trace.go:171","msg":"trace[1665423678] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"533.023215ms","start":"2026-07-25T16:30:32.223123Z","end":"2026-07-25T16:30:32.756146Z","steps":["trace[1665423678] 'process raft request' (duration: 532.855752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:32.756213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:30:32.222073Z","time spent":"534.059075ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:30:32.756303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:30:32.2231Z","time spent":"533.132577ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:30:34.05782Z","caller":"traceutil/trace.go:171","msg":"trace[1516893395] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"540.630284ms","start":"2026-07-25T16:30:33.517145Z","end":"2026-07-25T16:30:34.057775Z","steps":["trace[1516893395] 'process raft request' (duration: 540.119404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:34.057947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:30:33.517121Z","time spent":"540.759436ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:30:35.276448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.73778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T16:30:35.276533Z","caller":"traceutil/trace.go:171","msg":"trace[815938711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2685; }","duration":"124.888683ms","start":"2026-07-25T16:30:35.151625Z","end":"2026-07-25T16:30:35.276514Z","steps":["trace[815938711] 'range keys from in-memory index tree' (duration: 124.530016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:35.276681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.376749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:30:35.27677Z","caller":"traceutil/trace.go:171","msg":"trace[1020724168] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2685; }","duration":"171.519482ms","start":"2026-07-25T16:30:35.105232Z","end":"2026-07-25T16:30:35.276752Z","steps":["trace[1020724168] 'count revisions from in-memory index tree' (duration: 171.290158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:30:35.570453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.883996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:30:35.570669Z","caller":"traceutil/trace.go:171","msg":"trace[365614662] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"118.311655ms","start":"2026-07-25T16:30:35.452233Z","end":"2026-07-25T16:30:35.570544Z","steps":["trace[365614662] 'process raft request' (duration: 16.108014ms)","trace[365614662] 'compare' (duration: 101.750412ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:30:38.458966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1635} {"level":"info","ts":"2026-07-25T16:30:38.498168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1635,"took":"38.574241ms","hash":1546440699,"current-db-size-bytes":22970368,"current-db-size":"23 MB","current-db-size-in-use-bytes":5804032,"current-db-size-in-use":"5.8 MB"} {"level":"info","ts":"2026-07-25T16:30:38.498218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1546440699,"revision":1635,"compact-revision":-1} {"level":"warn","ts":"2026-07-25T16:31:02.209075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.259267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:31:02.209179Z","caller":"traceutil/trace.go:171","msg":"trace[921693853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2835; }","duration":"132.41446ms","start":"2026-07-25T16:31:02.076746Z","end":"2026-07-25T16:31:02.20916Z","steps":["trace[921693853] 'range keys from in-memory index tree' (duration: 132.188425ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:31:07.361921Z","caller":"traceutil/trace.go:171","msg":"trace[1478730882] linearizableReadLoop","detail":"{readStateIndex:3008; appliedIndex:3007; }","duration":"286.017323ms","start":"2026-07-25T16:31:07.075876Z","end":"2026-07-25T16:31:07.361893Z","steps":["trace[1478730882] 'read index received' (duration: 198.767203ms)","trace[1478730882] 'applied index is now lower than readState.Index' (duration: 87.24874ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:31:07.362064Z","caller":"traceutil/trace.go:171","msg":"trace[2005872006] transaction","detail":"{read_only:false; response_revision:2860; number_of_response:1; }","duration":"437.866461ms","start":"2026-07-25T16:31:06.924165Z","end":"2026-07-25T16:31:07.362032Z","steps":["trace[2005872006] 'process raft request' (duration: 350.570831ms)","trace[2005872006] 'compare' (duration: 86.979764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:31:07.362199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.17109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-25T16:31:07.362222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:31:06.924141Z","time spent":"437.992484ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:31:07.362276Z","caller":"traceutil/trace.go:171","msg":"trace[231259488] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2860; }","duration":"124.288683ms","start":"2026-07-25T16:31:07.237968Z","end":"2026-07-25T16:31:07.362257Z","steps":["trace[231259488] 'agreement among raft nodes before linearized reading' (duration: 124.102669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:31:07.362092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.208807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:31:07.362417Z","caller":"traceutil/trace.go:171","msg":"trace[2035350241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2860; }","duration":"286.591934ms","start":"2026-07-25T16:31:07.075816Z","end":"2026-07-25T16:31:07.362408Z","steps":["trace[2035350241] 'agreement among raft nodes before linearized reading' (duration: 286.222076ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:31:07.534201Z","caller":"traceutil/trace.go:171","msg":"trace[530048400] linearizableReadLoop","detail":"{readStateIndex:3009; appliedIndex:3008; }","duration":"100.349484ms","start":"2026-07-25T16:31:07.433827Z","end":"2026-07-25T16:31:07.534176Z","steps":["trace[530048400] 'read index received' (duration: 100.14725ms)","trace[530048400] 'applied index is now lower than readState.Index' (duration: 201.214µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:31:07.534316Z","caller":"traceutil/trace.go:171","msg":"trace[1360128453] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"167.637436ms","start":"2026-07-25T16:31:07.366649Z","end":"2026-07-25T16:31:07.534286Z","steps":["trace[1360128453] 'process raft request' (duration: 167.38275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:31:07.534348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.507969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T16:31:07.534408Z","caller":"traceutil/trace.go:171","msg":"trace[550404625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2861; }","duration":"100.58186ms","start":"2026-07-25T16:31:07.433812Z","end":"2026-07-25T16:31:07.534394Z","steps":["trace[550404625] 'agreement among raft nodes before linearized reading' (duration: 100.459938ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:35:38.464854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2710} {"level":"info","ts":"2026-07-25T16:35:38.486852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2710,"took":"21.385744ms","hash":2351294756,"current-db-size-bytes":22970368,"current-db-size":"23 MB","current-db-size-in-use-bytes":6266880,"current-db-size-in-use":"6.3 MB"} {"level":"info","ts":"2026-07-25T16:35:38.486906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2351294756,"revision":2710,"compact-revision":1635}