{"level":"warn","ts":"2026-05-21T19:27:39.737445Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:27:39.73752Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T19:27:39.737593Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:27:39.737606Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-21T19:27:39.737623Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:27:39.73828Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-05-21T19:27:39.738351Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T19:27:39.741208Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.560182ms"} {"level":"info","ts":"2026-05-21T19:27:39.748143Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-05-21T19:27:39.748219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:27:39.748242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-05-21T19:27:39.748254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:27:39.748263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-05-21T19:27:39.748306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-05-21T19:27:39.753298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:27:39.754744Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:27:39.756222Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T19:27:39.758353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:27:39.759659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:27:39.759731Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:27:39.75975Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:27:39.760962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:27:39.761966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-05-21T19:27:39.767357Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-21T19:27:39.768706Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:27:39.768821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-21T19:27:39.76886Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-21T19:27:39.769001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:27:39.769058Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:27:40.048695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:27:40.048773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:27:40.048814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-05-21T19:27:40.048834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:27:40.048845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-21T19:27:40.048869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-05-21T19:27:40.049039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-21T19:27:40.049939Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:27:40.050122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:27:40.050216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:27:40.050318Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:27:40.050565Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:27:40.050673Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:27:40.051217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:27:40.051361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:27:40.0514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:27:40.054209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-05-21T19:27:40.054506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-21T19:27:44.429987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.552252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:44.430116Z","caller":"traceutil/trace.go:171","msg":"trace[500021592] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:189; }","duration":"1.001497426s","start":"2026-05-21T19:27:43.428601Z","end":"2026-05-21T19:27:44.430098Z","steps":["trace[500021592] 'read index received' (duration: 181.292918ms)","trace[500021592] 'applied index is now lower than readState.Index' (duration: 820.202708ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:44.430215Z","caller":"traceutil/trace.go:171","msg":"trace[1063955333] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"1.495816051s","start":"2026-05-21T19:27:42.934319Z","end":"2026-05-21T19:27:44.430135Z","steps":["trace[1063955333] 'process raft request' (duration: 675.600303ms)","trace[1063955333] 'compare' (duration: 819.390468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:44.430379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:42.934302Z","time spent":"1.496006335s","remote":"127.0.0.1:59372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:44.430419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.663459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:27:44.430563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001460515s","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-05-21T19:27:44.430668Z","caller":"traceutil/trace.go:171","msg":"trace[1811285967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:187; }","duration":"1.001591528s","start":"2026-05-21T19:27:43.429056Z","end":"2026-05-21T19:27:44.430648Z","steps":["trace[1811285967] 'agreement among raft nodes before linearized reading' (duration: 1.001177829s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.430753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:43.429048Z","time spent":"1.001692141s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:27:44.430812Z","caller":"traceutil/trace.go:171","msg":"trace[1577311504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:187; }","duration":"961.788991ms","start":"2026-05-21T19:27:43.468679Z","end":"2026-05-21T19:27:44.430468Z","steps":["trace[1577311504] 'agreement among raft nodes before linearized reading' (duration: 961.591266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.430877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:43.468668Z","time spent":"962.193981ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:27:44.430865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002250284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T19:27:44.430973Z","caller":"traceutil/trace.go:171","msg":"trace[986556228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:187; }","duration":"1.002371937s","start":"2026-05-21T19:27:43.42856Z","end":"2026-05-21T19:27:44.430932Z","steps":["trace[986556228] 'agreement among raft nodes before linearized reading' (duration: 1.002183493s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.431017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:43.428536Z","time spent":"1.002472159s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:27:44.761814Z","caller":"traceutil/trace.go:171","msg":"trace[2029468304] linearizableReadLoop","detail":"{readStateIndex:192; appliedIndex:190; }","duration":"325.985655ms","start":"2026-05-21T19:27:44.435811Z","end":"2026-05-21T19:27:44.761796Z","steps":["trace[2029468304] 'read index received' (duration: 320.7432ms)","trace[2029468304] 'applied index is now lower than readState.Index' (duration: 5.241725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:44.761993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.172941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T19:27:44.761954Z","caller":"traceutil/trace.go:171","msg":"trace[55244066] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"327.03256ms","start":"2026-05-21T19:27:44.434847Z","end":"2026-05-21T19:27:44.761879Z","steps":["trace[55244066] 'process raft request' (duration: 321.61734ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:44.762022Z","caller":"traceutil/trace.go:171","msg":"trace[1163898967] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:189; }","duration":"326.252532ms","start":"2026-05-21T19:27:44.435763Z","end":"2026-05-21T19:27:44.762015Z","steps":["trace[1163898967] 'agreement among raft nodes before linearized reading' (duration: 326.140929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.762025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.411289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:44.762087Z","caller":"traceutil/trace.go:171","msg":"trace[1302413593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:189; }","duration":"193.504751ms","start":"2026-05-21T19:27:44.568569Z","end":"2026-05-21T19:27:44.762073Z","steps":["trace[1302413593] 'agreement among raft nodes before linearized reading' (duration: 193.389568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.762047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.435745Z","time spent":"326.295013ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-21T19:27:44.762431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.43483Z","time spent":"327.217986ms","remote":"127.0.0.1:59322","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-05-21T19:27:44.762528Z","caller":"traceutil/trace.go:171","msg":"trace[519585514] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"326.758874ms","start":"2026-05-21T19:27:44.435137Z","end":"2026-05-21T19:27:44.761896Z","steps":["trace[519585514] 'process raft request' (duration: 326.629121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.762662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.435124Z","time spent":"327.459171ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:04.547857Z","caller":"traceutil/trace.go:171","msg":"trace[838804574] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"150.063868ms","start":"2026-05-21T19:28:04.397758Z","end":"2026-05-21T19:28:04.547822Z","steps":["trace[838804574] 'process raft request' (duration: 149.832262ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:04.908089Z","caller":"traceutil/trace.go:171","msg":"trace[593486506] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"213.754559ms","start":"2026-05-21T19:28:04.694301Z","end":"2026-05-21T19:28:04.908055Z","steps":["trace[593486506] 'process raft request' (duration: 151.199285ms)","trace[593486506] 'compare' (duration: 62.307918ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:04.90815Z","caller":"traceutil/trace.go:171","msg":"trace[2081397529] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"211.69071ms","start":"2026-05-21T19:28:04.696437Z","end":"2026-05-21T19:28:04.908128Z","steps":["trace[2081397529] 'process raft request' (duration: 211.520236ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:04.908303Z","caller":"traceutil/trace.go:171","msg":"trace[1722105918] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"211.563717ms","start":"2026-05-21T19:28:04.696647Z","end":"2026-05-21T19:28:04.90821Z","steps":["trace[1722105918] 'process raft request' (duration: 211.387283ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:04.908496Z","caller":"traceutil/trace.go:171","msg":"trace[1606482551] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"211.70065ms","start":"2026-05-21T19:28:04.696765Z","end":"2026-05-21T19:28:04.908466Z","steps":["trace[1606482551] 'process raft request' (duration: 211.310231ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.151341Z","caller":"traceutil/trace.go:171","msg":"trace[1314850765] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"183.454531ms","start":"2026-05-21T19:28:04.967863Z","end":"2026-05-21T19:28:05.151317Z","steps":["trace[1314850765] 'process raft request' (duration: 183.378949ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.151335Z","caller":"traceutil/trace.go:171","msg":"trace[299890873] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"233.032583ms","start":"2026-05-21T19:28:04.918244Z","end":"2026-05-21T19:28:05.151276Z","steps":["trace[299890873] 'process raft request' (duration: 148.724586ms)","trace[299890873] 'compare' (duration: 84.043151ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:05.151569Z","caller":"traceutil/trace.go:171","msg":"trace[1786798619] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"184.808903ms","start":"2026-05-21T19:28:04.96673Z","end":"2026-05-21T19:28:05.151539Z","steps":["trace[1786798619] 'read index received' (duration: 100.24587ms)","trace[1786798619] 'applied index is now lower than readState.Index' (duration: 84.557753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:05.151718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.464703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-f7sgk\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-05-21T19:28:05.151776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.786481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-21T19:28:05.151787Z","caller":"traceutil/trace.go:171","msg":"trace[1210188216] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-f7sgk; range_end:; response_count:1; response_revision:405; }","duration":"233.581506ms","start":"2026-05-21T19:28:04.918187Z","end":"2026-05-21T19:28:05.151769Z","steps":["trace[1210188216] 'agreement among raft nodes before linearized reading' (duration: 233.402921ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.151815Z","caller":"traceutil/trace.go:171","msg":"trace[1203532200] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:405; }","duration":"233.864823ms","start":"2026-05-21T19:28:04.917935Z","end":"2026-05-21T19:28:05.1518Z","steps":["trace[1203532200] 'agreement among raft nodes before linearized reading' (duration: 233.75496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.15182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.047915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:28:05.151852Z","caller":"traceutil/trace.go:171","msg":"trace[1076094845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"174.091026ms","start":"2026-05-21T19:28:04.977751Z","end":"2026-05-21T19:28:05.151843Z","steps":["trace[1076094845] 'agreement among raft nodes before linearized reading' (duration: 174.005593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.151975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.242062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-05-21T19:28:05.151999Z","caller":"traceutil/trace.go:171","msg":"trace[1646737616] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:405; }","duration":"234.297933ms","start":"2026-05-21T19:28:04.917695Z","end":"2026-05-21T19:28:05.151993Z","steps":["trace[1646737616] 'agreement among raft nodes before linearized reading' (duration: 234.252332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.152097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.302937ms","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-05-21T19:28:05.152118Z","caller":"traceutil/trace.go:171","msg":"trace[1422869729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"126.355898ms","start":"2026-05-21T19:28:05.025757Z","end":"2026-05-21T19:28:05.152113Z","steps":["trace[1422869729] 'agreement among raft nodes before linearized reading' (duration: 126.321737ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:15.388151Z","caller":"traceutil/trace.go:171","msg":"trace[882719770] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"169.2976ms","start":"2026-05-21T19:28:15.218833Z","end":"2026-05-21T19:28:15.388131Z","steps":["trace[882719770] 'process raft request' (duration: 169.197558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:15.389803Z","caller":"traceutil/trace.go:171","msg":"trace[681090380] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"170.356026ms","start":"2026-05-21T19:28:15.219421Z","end":"2026-05-21T19:28:15.389777Z","steps":["trace[681090380] 'process raft request' (duration: 170.213952ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:17.760843Z","caller":"traceutil/trace.go:171","msg":"trace[1664784377] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"363.569751ms","start":"2026-05-21T19:28:17.397253Z","end":"2026-05-21T19:28:17.760823Z","steps":["trace[1664784377] 'process raft request' (duration: 363.469618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.760996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:17.397235Z","time spent":"363.701915ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:18.149773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.268699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:18.149902Z","caller":"traceutil/trace.go:171","msg":"trace[1446060646] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"280.34561ms","start":"2026-05-21T19:28:17.869531Z","end":"2026-05-21T19:28:18.149877Z","steps":["trace[1446060646] 'read index received' (duration: 42.411µs)","trace[1446060646] 'applied index is now lower than readState.Index' (duration: 280.302059ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:18.149994Z","caller":"traceutil/trace.go:171","msg":"trace[2133026563] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"752.37949ms","start":"2026-05-21T19:28:17.397604Z","end":"2026-05-21T19:28:18.149984Z","steps":["trace[2133026563] 'process raft request' (duration: 466.784773ms)","trace[2133026563] 'compare' (duration: 285.155196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:18.150059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:17.397588Z","time spent":"752.439751ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:18.150076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.379282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:18.150145Z","caller":"traceutil/trace.go:171","msg":"trace[2059489045] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:487; }","duration":"248.492934ms","start":"2026-05-21T19:28:17.901634Z","end":"2026-05-21T19:28:18.150127Z","steps":["trace[2059489045] 'agreement among raft nodes before linearized reading' (duration: 248.361951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:18.15015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.602877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:28:18.150226Z","caller":"traceutil/trace.go:171","msg":"trace[916976383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"280.722919ms","start":"2026-05-21T19:28:17.869483Z","end":"2026-05-21T19:28:18.150206Z","steps":["trace[916976383] 'agreement among raft nodes before linearized reading' (duration: 280.597926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:18.150403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.055477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:28:18.150678Z","caller":"traceutil/trace.go:171","msg":"trace[2015012508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"120.365604ms","start":"2026-05-21T19:28:18.0303Z","end":"2026-05-21T19:28:18.150666Z","steps":["trace[2015012508] 'agreement among raft nodes before linearized reading' (duration: 120.055787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.342257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197366865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:28:19.763583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029489682s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:19.763902Z","caller":"traceutil/trace.go:171","msg":"trace[1130821435] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.103291417s","start":"2026-05-21T19:28:18.660586Z","end":"2026-05-21T19:28:19.763877Z","steps":["trace[1130821435] 'process raft request' (duration: 73.385875ms)","trace[1130821435] 'compare' (duration: 1.029285698s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:19.764022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:18.660559Z","time spent":"1.10342379s","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:19.764332Z","caller":"traceutil/trace.go:171","msg":"trace[962168663] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.096373801s","start":"2026-05-21T19:28:18.667948Z","end":"2026-05-21T19:28:19.764321Z","steps":["trace[962168663] 'process raft request' (duration: 1.095879349s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.764446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:18.667922Z","time spent":"1.096488153s","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:19.784769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050545528s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:28:19.785086Z","caller":"traceutil/trace.go:171","msg":"trace[197185824] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.117000907s","start":"2026-05-21T19:28:18.66807Z","end":"2026-05-21T19:28:19.785071Z","steps":["trace[197185824] 'process raft request' (duration: 1.116931005s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.785187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:18.668061Z","time spent":"1.117083169s","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:19.79453Z","caller":"traceutil/trace.go:171","msg":"trace[248486599] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"952.503662ms","start":"2026-05-21T19:28:18.842Z","end":"2026-05-21T19:28:19.794504Z","steps":["trace[248486599] 'read index received' (duration: 943.113746ms)","trace[248486599] 'applied index is now lower than readState.Index' (duration: 9.389216ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:19.794601Z","caller":"traceutil/trace.go:171","msg":"trace[1391140369] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.005966737s","start":"2026-05-21T19:28:18.788578Z","end":"2026-05-21T19:28:19.794544Z","steps":["trace[1391140369] 'process raft request' (duration: 1.005727541s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.79504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:18.788547Z","time spent":"1.006464169s","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:28:19.79474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.724667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:19.795097Z","caller":"traceutil/trace.go:171","msg":"trace[155672893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"953.140047ms","start":"2026-05-21T19:28:18.841951Z","end":"2026-05-21T19:28:19.795091Z","steps":["trace[155672893] 'agreement among raft nodes before linearized reading' (duration: 952.666265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.795115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:18.841933Z","time spent":"953.174847ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:25.512786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.36423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:25.512932Z","caller":"traceutil/trace.go:171","msg":"trace[349586197] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:582; }","duration":"156.684518ms","start":"2026-05-21T19:28:25.356214Z","end":"2026-05-21T19:28:25.512898Z","steps":["trace[349586197] 'range keys from in-memory index tree' (duration: 156.167685ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:25.996872Z","caller":"traceutil/trace.go:171","msg":"trace[140509705] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"146.79119ms","start":"2026-05-21T19:28:25.850018Z","end":"2026-05-21T19:28:25.996809Z","steps":["trace[140509705] 'process raft request' (duration: 81.630223ms)","trace[140509705] 'compare' (duration: 64.929291ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:26.214259Z","caller":"traceutil/trace.go:171","msg":"trace[425016000] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"149.082725ms","start":"2026-05-21T19:28:26.065131Z","end":"2026-05-21T19:28:26.214213Z","steps":["trace[425016000] 'process raft request' (duration: 62.585015ms)","trace[425016000] 'compare' (duration: 86.266974ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:27.237961Z","caller":"traceutil/trace.go:171","msg":"trace[159234430] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"404.83057ms","start":"2026-05-21T19:28:26.833102Z","end":"2026-05-21T19:28:27.237933Z","steps":["trace[159234430] 'read index received' (duration: 403.831924ms)","trace[159234430] 'applied index is now lower than readState.Index' (duration: 997.766µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:27.238135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.256366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:27.238167Z","caller":"traceutil/trace.go:171","msg":"trace[1886562397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"399.322797ms","start":"2026-05-21T19:28:26.838833Z","end":"2026-05-21T19:28:27.238156Z","steps":["trace[1886562397] 'agreement among raft nodes before linearized reading' (duration: 399.244775ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:27.238144Z","caller":"traceutil/trace.go:171","msg":"trace[2045892930] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"593.905286ms","start":"2026-05-21T19:28:26.644209Z","end":"2026-05-21T19:28:27.238115Z","steps":["trace[2045892930] 'process raft request' (duration: 593.072235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:27.238204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:26.838825Z","time spent":"399.372338ms","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:27.238261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.128177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vqkgl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T19:28:27.238317Z","caller":"traceutil/trace.go:171","msg":"trace[611811369] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vqkgl; range_end:; response_count:1; response_revision:592; }","duration":"405.24244ms","start":"2026-05-21T19:28:26.833058Z","end":"2026-05-21T19:28:27.238301Z","steps":["trace[611811369] 'agreement among raft nodes before linearized reading' (duration: 405.016674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:27.238316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:26.644175Z","time spent":"594.058929ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:27.238351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:26.833038Z","time spent":"405.305801ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vqkgl\" "} {"level":"info","ts":"2026-05-21T19:28:27.909307Z","caller":"traceutil/trace.go:171","msg":"trace[523058844] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"656.788361ms","start":"2026-05-21T19:28:27.252485Z","end":"2026-05-21T19:28:27.909273Z","steps":["trace[523058844] 'process raft request' (duration: 656.517562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:27.909522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:27.252464Z","time spent":"656.944736ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:29.378931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091832245s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:29.379111Z","caller":"traceutil/trace.go:171","msg":"trace[769864975] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"1.537311242s","start":"2026-05-21T19:28:27.841783Z","end":"2026-05-21T19:28:29.379094Z","steps":["trace[769864975] 'read index received' (duration: 67.239769ms)","trace[769864975] 'applied index is now lower than readState.Index' (duration: 1.470070413s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:29.379193Z","caller":"traceutil/trace.go:171","msg":"trace[1036319523] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.065565101s","start":"2026-05-21T19:28:27.313618Z","end":"2026-05-21T19:28:29.379183Z","steps":["trace[1036319523] 'process raft request' (duration: 973.40038ms)","trace[1036319523] 'compare' (duration: 1.091649699s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:29.379248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:27.31359Z","time spent":"2.065632332s","remote":"127.0.0.1:59372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:28:29.379791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523048241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:28:29.379892Z","caller":"traceutil/trace.go:171","msg":"trace[577505393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.5231904s","start":"2026-05-21T19:28:27.856682Z","end":"2026-05-21T19:28:29.379872Z","steps":["trace[577505393] 'agreement among raft nodes before linearized reading' (duration: 1.522893263s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.379935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:27.856649Z","time spent":"1.52327663s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:28:29.379922Z","caller":"traceutil/trace.go:171","msg":"trace[2035975227] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.935713564s","start":"2026-05-21T19:28:27.444114Z","end":"2026-05-21T19:28:29.379827Z","steps":["trace[2035975227] 'process raft request' (duration: 1.934910715s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.380153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:27.444082Z","time spent":"1.935943669s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:29.380203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085672733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:28:29.380248Z","caller":"traceutil/trace.go:171","msg":"trace[1157877346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"1.085735849s","start":"2026-05-21T19:28:28.294498Z","end":"2026-05-21T19:28:29.380234Z","steps":["trace[1157877346] 'agreement among raft nodes before linearized reading' (duration: 1.085639117s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.380283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:28.294485Z","time spent":"1.085790704s","remote":"127.0.0.1:59322","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-05-21T19:28:29.380415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128673028s","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-05-21T19:28:29.380443Z","caller":"traceutil/trace.go:171","msg":"trace[1508371703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.12874214s","start":"2026-05-21T19:28:28.251693Z","end":"2026-05-21T19:28:29.380435Z","steps":["trace[1508371703] 'agreement among raft nodes before linearized reading' (duration: 1.128659628s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.380469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:28.25166Z","time spent":"1.12880306s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:29.381065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539280519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:29.381106Z","caller":"traceutil/trace.go:171","msg":"trace[1048899887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"1.53937591s","start":"2026-05-21T19:28:27.841719Z","end":"2026-05-21T19:28:29.381095Z","steps":["trace[1048899887] 'agreement among raft nodes before linearized reading' (duration: 1.539312189s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.381136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:27.841688Z","time spent":"1.539439252s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:29.381656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087100253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:28:29.381707Z","caller":"traceutil/trace.go:171","msg":"trace[603347980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"1.087192114s","start":"2026-05-21T19:28:28.294501Z","end":"2026-05-21T19:28:29.381693Z","steps":["trace[603347980] 'agreement among raft nodes before linearized reading' (duration: 1.085252488s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:29.381742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:28.294472Z","time spent":"1.087263262s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:28:30.973285Z","caller":"traceutil/trace.go:171","msg":"trace[849115890] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.069172247s","start":"2026-05-21T19:28:29.904089Z","end":"2026-05-21T19:28:30.973262Z","steps":["trace[849115890] 'process raft request' (duration: 1.068152073s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:30.973552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:29.904055Z","time spent":"1.069387601s","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:31.055781Z","caller":"traceutil/trace.go:171","msg":"trace[842160351] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"213.668575ms","start":"2026-05-21T19:28:30.842072Z","end":"2026-05-21T19:28:31.055741Z","steps":["trace[842160351] 'read index received' (duration: 129.967249ms)","trace[842160351] 'applied index is now lower than readState.Index' (duration: 83.699456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:31.05595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.85484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:31.056036Z","caller":"traceutil/trace.go:171","msg":"trace[119041585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"213.999433ms","start":"2026-05-21T19:28:30.842016Z","end":"2026-05-21T19:28:31.056015Z","steps":["trace[119041585] 'agreement among raft nodes before linearized reading' (duration: 213.840179ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:32.188088Z","caller":"traceutil/trace.go:171","msg":"trace[378887702] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"174.696555ms","start":"2026-05-21T19:28:32.013368Z","end":"2026-05-21T19:28:32.188065Z","steps":["trace[378887702] 'process raft request' (duration: 173.583839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.338095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.315778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:34.33823Z","caller":"traceutil/trace.go:171","msg":"trace[977743655] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"455.505756ms","start":"2026-05-21T19:28:33.882705Z","end":"2026-05-21T19:28:34.338211Z","steps":["trace[977743655] 'process raft request' (duration: 238.985373ms)","trace[977743655] 'compare' (duration: 216.107393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:34.338315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.882685Z","time spent":"455.592848ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:34.347097Z","caller":"traceutil/trace.go:171","msg":"trace[432993718] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"136.541214ms","start":"2026-05-21T19:28:34.21052Z","end":"2026-05-21T19:28:34.347061Z","steps":["trace[432993718] 'read index received' (duration: 136.149265ms)","trace[432993718] 'applied index is now lower than readState.Index' (duration: 390.769µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:34.347294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.726538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:34.347336Z","caller":"traceutil/trace.go:171","msg":"trace[2030461372] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:668; }","duration":"136.835111ms","start":"2026-05-21T19:28:34.210487Z","end":"2026-05-21T19:28:34.347322Z","steps":["trace[2030461372] 'agreement among raft nodes before linearized reading' (duration: 136.702988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.34767Z","caller":"traceutil/trace.go:171","msg":"trace[1094292254] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"331.039155ms","start":"2026-05-21T19:28:34.016598Z","end":"2026-05-21T19:28:34.347638Z","steps":["trace[1094292254] 'process raft request' (duration: 330.37318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.347761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.016563Z","time spent":"331.158478ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:28:34.347566Z","caller":"traceutil/trace.go:171","msg":"trace[798434351] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"463.492464ms","start":"2026-05-21T19:28:33.884041Z","end":"2026-05-21T19:28:34.347533Z","steps":["trace[798434351] 'process raft request' (duration: 462.746386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.34801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.884019Z","time spent":"463.920394ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:34.91217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197367216,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:28:34.945179Z","caller":"traceutil/trace.go:171","msg":"trace[715830493] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"542.258195ms","start":"2026-05-21T19:28:34.402885Z","end":"2026-05-21T19:28:34.945144Z","steps":["trace[715830493] 'process raft request' (duration: 523.317128ms)","trace[715830493] 'compare' (duration: 18.803454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:34.945375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.402855Z","time spent":"542.418778ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:34.964142Z","caller":"traceutil/trace.go:171","msg":"trace[276393396] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"552.377284ms","start":"2026-05-21T19:28:34.41173Z","end":"2026-05-21T19:28:34.964107Z","steps":["trace[276393396] 'read index received' (duration: 514.494489ms)","trace[276393396] 'applied index is now lower than readState.Index' (duration: 37.881195ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:34.964205Z","caller":"traceutil/trace.go:171","msg":"trace[618690673] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"558.525569ms","start":"2026-05-21T19:28:34.405629Z","end":"2026-05-21T19:28:34.964154Z","steps":["trace[618690673] 'process raft request' (duration: 558.310854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.964387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.405587Z","time spent":"558.718004ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:28:34.964496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.208196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:34.964489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.701042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:34.964572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.809076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-21T19:28:34.964596Z","caller":"traceutil/trace.go:171","msg":"trace[556179370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"122.368799ms","start":"2026-05-21T19:28:34.842209Z","end":"2026-05-21T19:28:34.964578Z","steps":["trace[556179370] 'agreement among raft nodes before linearized reading' (duration: 122.159794ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.964651Z","caller":"traceutil/trace.go:171","msg":"trace[386767263] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"154.910258ms","start":"2026-05-21T19:28:34.80972Z","end":"2026-05-21T19:28:34.96463Z","steps":["trace[386767263] 'agreement among raft nodes before linearized reading' (duration: 154.678333ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.964681Z","caller":"traceutil/trace.go:171","msg":"trace[1586593899] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:671; }","duration":"552.923117ms","start":"2026-05-21T19:28:34.411695Z","end":"2026-05-21T19:28:34.964618Z","steps":["trace[1586593899] 'agreement among raft nodes before linearized reading' (duration: 552.584679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.964702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.682605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-21T19:28:34.96477Z","caller":"traceutil/trace.go:171","msg":"trace[1899623507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"354.836929ms","start":"2026-05-21T19:28:34.609907Z","end":"2026-05-21T19:28:34.964744Z","steps":["trace[1899623507] 'agreement among raft nodes before linearized reading' (duration: 354.512252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.964771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.41168Z","time spent":"553.04752ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-21T19:28:34.964823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.609891Z","time spent":"354.921771ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-21T19:28:34.96516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.157961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:34.965214Z","caller":"traceutil/trace.go:171","msg":"trace[1737024030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"536.240633ms","start":"2026-05-21T19:28:34.428958Z","end":"2026-05-21T19:28:34.965198Z","steps":["trace[1737024030] 'agreement among raft nodes before linearized reading' (duration: 535.326851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.965255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.428939Z","time spent":"536.307605ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:28:45.739017Z","caller":"traceutil/trace.go:171","msg":"trace[1969353126] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"183.639586ms","start":"2026-05-21T19:28:45.55535Z","end":"2026-05-21T19:28:45.738989Z","steps":["trace[1969353126] 'process raft request' (duration: 182.332485ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:45.739497Z","caller":"traceutil/trace.go:171","msg":"trace[160101336] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"181.809284ms","start":"2026-05-21T19:28:45.557632Z","end":"2026-05-21T19:28:45.739441Z","steps":["trace[160101336] 'process raft request' (duration: 181.738492ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:45.740047Z","caller":"traceutil/trace.go:171","msg":"trace[893866126] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"182.181712ms","start":"2026-05-21T19:28:45.557364Z","end":"2026-05-21T19:28:45.739546Z","steps":["trace[893866126] 'process raft request' (duration: 181.881725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:48.813212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197367454,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:28:49.314281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197367454,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:28:49.814981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197367454,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:28:50.034868Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.953655633s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:28:50.035362Z","caller":"traceutil/trace.go:171","msg":"trace[2027283453] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.954214356s","start":"2026-05-21T19:28:48.081127Z","end":"2026-05-21T19:28:50.035342Z","steps":["trace[2027283453] 'process raft request' (duration: 1.954070093s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.035638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:48.081104Z","time spent":"1.954312508s","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:50.055843Z","caller":"traceutil/trace.go:171","msg":"trace[1491982128] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.730270228s","start":"2026-05-21T19:28:48.325541Z","end":"2026-05-21T19:28:50.055811Z","steps":["trace[1491982128] 'process raft request' (duration: 1.730086704s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.056036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:48.325521Z","time spent":"1.730418491s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:50.056024Z","caller":"traceutil/trace.go:171","msg":"trace[1222726975] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"1.743845129s","start":"2026-05-21T19:28:48.31216Z","end":"2026-05-21T19:28:50.056005Z","steps":["trace[1222726975] 'read index received' (duration: 1.723020837s)","trace[1222726975] 'applied index is now lower than readState.Index' (duration: 20.822772ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:50.056203Z","caller":"traceutil/trace.go:171","msg":"trace[1595539547] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"223.860246ms","start":"2026-05-21T19:28:49.832331Z","end":"2026-05-21T19:28:50.056191Z","steps":["trace[1595539547] 'process raft request' (duration: 223.430706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.056423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479425316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:50.056501Z","caller":"traceutil/trace.go:171","msg":"trace[298154973] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:778; }","duration":"1.479561649s","start":"2026-05-21T19:28:48.576922Z","end":"2026-05-21T19:28:50.056484Z","steps":["trace[298154973] 'agreement among raft nodes before linearized reading' (duration: 1.479430146s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.056559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:48.576902Z","time spent":"1.47964331s","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:28:50.056608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.35015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:28:50.056696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007361108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:28:50.056709Z","caller":"traceutil/trace.go:171","msg":"trace[559888275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"305.462413ms","start":"2026-05-21T19:28:49.751229Z","end":"2026-05-21T19:28:50.056692Z","steps":["trace[559888275] 'agreement among raft nodes before linearized reading' (duration: 305.189566ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:50.056777Z","caller":"traceutil/trace.go:171","msg":"trace[787494166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.00747589s","start":"2026-05-21T19:28:49.049283Z","end":"2026-05-21T19:28:50.056759Z","steps":["trace[787494166] 'agreement among raft nodes before linearized reading' (duration: 1.007286186s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.056825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:49.049266Z","time spent":"1.007548603s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:50.056824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213928456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:50.056873Z","caller":"traceutil/trace.go:171","msg":"trace[933396837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"1.214004388s","start":"2026-05-21T19:28:48.842855Z","end":"2026-05-21T19:28:50.05686Z","steps":["trace[933396837] 'agreement among raft nodes before linearized reading' (duration: 1.213932966s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.056944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:48.842836Z","time spent":"1.21409796s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:50.057065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.469904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:28:50.057105Z","caller":"traceutil/trace.go:171","msg":"trace[1166058723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"524.545326ms","start":"2026-05-21T19:28:49.53255Z","end":"2026-05-21T19:28:50.057095Z","steps":["trace[1166058723] 'agreement among raft nodes before linearized reading' (duration: 524.442284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.057133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:49.53253Z","time spent":"524.596658ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:28:50.056789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:49.751171Z","time spent":"305.606407ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:50.058467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746303896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:50.058523Z","caller":"traceutil/trace.go:171","msg":"trace[718362325] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:778; }","duration":"1.746403999s","start":"2026-05-21T19:28:48.312106Z","end":"2026-05-21T19:28:50.05851Z","steps":["trace[718362325] 'agreement among raft nodes before linearized reading' (duration: 1.743977151s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.058602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:48.312082Z","time spent":"1.746507452s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:28:50.059449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.283947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:28:50.059537Z","caller":"traceutil/trace.go:171","msg":"trace[2128492964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"307.372659ms","start":"2026-05-21T19:28:49.752149Z","end":"2026-05-21T19:28:50.059521Z","steps":["trace[2128492964] 'agreement among raft nodes before linearized reading' (duration: 305.049754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.059919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:49.752116Z","time spent":"307.766277ms","remote":"127.0.0.1:59322","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-05-21T19:28:50.060214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.014074ms","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-05-21T19:28:50.060259Z","caller":"traceutil/trace.go:171","msg":"trace[1537552608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"308.075215ms","start":"2026-05-21T19:28:49.752173Z","end":"2026-05-21T19:28:50.060249Z","steps":["trace[1537552608] 'agreement among raft nodes before linearized reading' (duration: 304.675665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:50.06029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:49.752167Z","time spent":"308.115866ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:28:59.463971Z","caller":"traceutil/trace.go:171","msg":"trace[281436459] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"204.170771ms","start":"2026-05-21T19:28:59.259764Z","end":"2026-05-21T19:28:59.463935Z","steps":["trace[281436459] 'process raft request' (duration: 137.460426ms)","trace[281436459] 'compare' (duration: 66.454849ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:59.464058Z","caller":"traceutil/trace.go:171","msg":"trace[9602419] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:912; }","duration":"204.11933ms","start":"2026-05-21T19:28:59.259914Z","end":"2026-05-21T19:28:59.464033Z","steps":["trace[9602419] 'read index received' (duration: 17.928124ms)","trace[9602419] 'applied index is now lower than readState.Index' (duration: 186.190216ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:59.464126Z","caller":"traceutil/trace.go:171","msg":"trace[335253306] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"204.285464ms","start":"2026-05-21T19:28:59.259812Z","end":"2026-05-21T19:28:59.464098Z","steps":["trace[335253306] 'process raft request' (duration: 204.073589ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:59.464091Z","caller":"traceutil/trace.go:171","msg":"trace[1685839765] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"204.13827ms","start":"2026-05-21T19:28:59.259931Z","end":"2026-05-21T19:28:59.464069Z","steps":["trace[1685839765] 'process raft request' (duration: 203.999567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:59.464574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.652313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-05-21T19:28:59.46461Z","caller":"traceutil/trace.go:171","msg":"trace[808772228] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:889; }","duration":"204.721215ms","start":"2026-05-21T19:28:59.25988Z","end":"2026-05-21T19:28:59.464602Z","steps":["trace[808772228] 'agreement among raft nodes before linearized reading' (duration: 204.588642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:59.46464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.326541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4228"} {"level":"info","ts":"2026-05-21T19:28:59.464703Z","caller":"traceutil/trace.go:171","msg":"trace[236444668] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:889; }","duration":"203.422104ms","start":"2026-05-21T19:28:59.261265Z","end":"2026-05-21T19:28:59.464687Z","steps":["trace[236444668] 'agreement among raft nodes before linearized reading' (duration: 203.212269ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:59.464598Z","caller":"traceutil/trace.go:171","msg":"trace[157489297] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"204.56052ms","start":"2026-05-21T19:28:59.260025Z","end":"2026-05-21T19:28:59.464586Z","steps":["trace[157489297] 'process raft request' (duration: 203.963856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:59.464715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.581531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-mlbgl\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-05-21T19:28:59.46479Z","caller":"traceutil/trace.go:171","msg":"trace[1670585658] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-mlbgl; range_end:; response_count:1; response_revision:889; }","duration":"204.693134ms","start":"2026-05-21T19:28:59.260076Z","end":"2026-05-21T19:28:59.464769Z","steps":["trace[1670585658] 'agreement among raft nodes before linearized reading' (duration: 204.404387ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:59.635803Z","caller":"traceutil/trace.go:171","msg":"trace[841928220] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"161.101834ms","start":"2026-05-21T19:28:59.474678Z","end":"2026-05-21T19:28:59.63578Z","steps":["trace[841928220] 'process raft request' (duration: 160.91741ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:59.720707Z","caller":"traceutil/trace.go:171","msg":"trace[852409773] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"245.499707ms","start":"2026-05-21T19:28:59.475183Z","end":"2026-05-21T19:28:59.720683Z","steps":["trace[852409773] 'process raft request' (duration: 245.365964ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:02.423788Z","caller":"traceutil/trace.go:171","msg":"trace[1138878487] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"210.02647ms","start":"2026-05-21T19:29:02.213739Z","end":"2026-05-21T19:29:02.423766Z","steps":["trace[1138878487] 'process raft request' (duration: 209.804195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:02.423958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.480002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:29:02.42404Z","caller":"traceutil/trace.go:171","msg":"trace[593691370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"216.591215ms","start":"2026-05-21T19:29:02.207426Z","end":"2026-05-21T19:29:02.424017Z","steps":["trace[593691370] 'agreement among raft nodes before linearized reading' (duration: 216.287318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:02.424193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.005934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T19:29:02.424582Z","caller":"traceutil/trace.go:171","msg":"trace[151099909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"217.490175ms","start":"2026-05-21T19:29:02.207069Z","end":"2026-05-21T19:29:02.424559Z","steps":["trace[151099909] 'agreement among raft nodes before linearized reading' (duration: 216.908432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:02.424444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.643819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:29:02.424239Z","caller":"traceutil/trace.go:171","msg":"trace[688389603] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"160.458859ms","start":"2026-05-21T19:29:02.263227Z","end":"2026-05-21T19:29:02.423686Z","steps":["trace[688389603] 'read index received' (duration: 160.340416ms)","trace[688389603] 'applied index is now lower than readState.Index' (duration: 117.313µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:02.42469Z","caller":"traceutil/trace.go:171","msg":"trace[1713645442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"217.898166ms","start":"2026-05-21T19:29:02.20676Z","end":"2026-05-21T19:29:02.424658Z","steps":["trace[1713645442] 'agreement among raft nodes before linearized reading' (duration: 217.514647ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:02.514684Z","caller":"traceutil/trace.go:171","msg":"trace[743616355] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"246.548332ms","start":"2026-05-21T19:29:02.268111Z","end":"2026-05-21T19:29:02.51466Z","steps":["trace[743616355] 'process raft request' (duration: 205.103863ms)","trace[743616355] 'compare' (duration: 41.282775ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:03.71825Z","caller":"traceutil/trace.go:171","msg":"trace[515056666] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"176.657701ms","start":"2026-05-21T19:29:03.54157Z","end":"2026-05-21T19:29:03.718228Z","steps":["trace[515056666] 'process raft request' (duration: 176.506998ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:03.914252Z","caller":"traceutil/trace.go:171","msg":"trace[486942522] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"190.188832ms","start":"2026-05-21T19:29:03.724033Z","end":"2026-05-21T19:29:03.914222Z","steps":["trace[486942522] 'process raft request' (duration: 90.676271ms)","trace[486942522] 'compare' (duration: 99.289935ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:03.935605Z","caller":"traceutil/trace.go:171","msg":"trace[554369063] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:937; }","duration":"122.673697ms","start":"2026-05-21T19:29:03.812905Z","end":"2026-05-21T19:29:03.935578Z","steps":["trace[554369063] 'read index received' (duration: 1.812103ms)","trace[554369063] 'applied index is now lower than readState.Index' (duration: 120.860704ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:03.93571Z","caller":"traceutil/trace.go:171","msg":"trace[1384551734] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"208.830651ms","start":"2026-05-21T19:29:03.726851Z","end":"2026-05-21T19:29:03.935682Z","steps":["trace[1384551734] 'process raft request' (duration: 208.666147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:03.935805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.878851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:29:03.935823Z","caller":"traceutil/trace.go:171","msg":"trace[1439477312] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"210.413309ms","start":"2026-05-21T19:29:03.725385Z","end":"2026-05-21T19:29:03.935799Z","steps":["trace[1439477312] 'process raft request' (duration: 209.979919ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:03.935864Z","caller":"traceutil/trace.go:171","msg":"trace[873084608] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:911; }","duration":"122.985474ms","start":"2026-05-21T19:29:03.812862Z","end":"2026-05-21T19:29:03.935847Z","steps":["trace[873084608] 'agreement among raft nodes before linearized reading' (duration: 122.846771ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:04.5354Z","caller":"traceutil/trace.go:171","msg":"trace[749850543] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"106.662908ms","start":"2026-05-21T19:29:04.428712Z","end":"2026-05-21T19:29:04.535375Z","steps":["trace[749850543] 'process raft request' (duration: 97.932672ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:18.595817Z","caller":"traceutil/trace.go:171","msg":"trace[721078916] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"106.252569ms","start":"2026-05-21T19:29:18.489541Z","end":"2026-05-21T19:29:18.595794Z","steps":["trace[721078916] 'process raft request' (duration: 106.111085ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:20.831304Z","caller":"traceutil/trace.go:171","msg":"trace[1163893999] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"121.050419ms","start":"2026-05-21T19:29:20.710231Z","end":"2026-05-21T19:29:20.831281Z","steps":["trace[1163893999] 'process raft request' (duration: 84.556567ms)","trace[1163893999] 'compare' (duration: 36.318158ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:20.878911Z","caller":"traceutil/trace.go:171","msg":"trace[1918290719] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"168.580511ms","start":"2026-05-21T19:29:20.710304Z","end":"2026-05-21T19:29:20.878885Z","steps":["trace[1918290719] 'process raft request' (duration: 168.284074ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:20.878935Z","caller":"traceutil/trace.go:171","msg":"trace[1781741564] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"168.134841ms","start":"2026-05-21T19:29:20.710775Z","end":"2026-05-21T19:29:20.87891Z","steps":["trace[1781741564] 'process raft request' (duration: 168.069829ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:20.878923Z","caller":"traceutil/trace.go:171","msg":"trace[20892236] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"168.456718ms","start":"2026-05-21T19:29:20.710447Z","end":"2026-05-21T19:29:20.878904Z","steps":["trace[20892236] 'process raft request' (duration: 168.340836ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:20.952722Z","caller":"traceutil/trace.go:171","msg":"trace[920376907] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1006; }","duration":"112.763783ms","start":"2026-05-21T19:29:20.839935Z","end":"2026-05-21T19:29:20.952699Z","steps":["trace[920376907] 'read index received' (duration: 38.741895ms)","trace[920376907] 'applied index is now lower than readState.Index' (duration: 74.020948ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:20.952814Z","caller":"traceutil/trace.go:171","msg":"trace[1201860826] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"127.403238ms","start":"2026-05-21T19:29:20.825387Z","end":"2026-05-21T19:29:20.95279Z","steps":["trace[1201860826] 'process raft request' (duration: 127.180233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:20.952856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.906116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:20.952887Z","caller":"traceutil/trace.go:171","msg":"trace[1533271140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"112.984768ms","start":"2026-05-21T19:29:20.839893Z","end":"2026-05-21T19:29:20.952878Z","steps":["trace[1533271140] 'agreement among raft nodes before linearized reading' (duration: 112.892576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:25.342103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197367931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:29:25.523092Z","caller":"traceutil/trace.go:171","msg":"trace[1988220501] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"834.968047ms","start":"2026-05-21T19:29:24.688102Z","end":"2026-05-21T19:29:25.52307Z","steps":["trace[1988220501] 'process raft request' (duration: 834.809323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:25.523234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.688088Z","time spent":"835.072079ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:26.571653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.170188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:29:26.571723Z","caller":"traceutil/trace.go:171","msg":"trace[1768421047] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"1.730242588s","start":"2026-05-21T19:29:24.841469Z","end":"2026-05-21T19:29:26.571712Z","steps":["trace[1768421047] 'read index received' (duration: 681.428641ms)","trace[1768421047] 'applied index is now lower than readState.Index' (duration: 1.048813167s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:26.57179Z","caller":"traceutil/trace.go:171","msg":"trace[2018205657] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.85353112s","start":"2026-05-21T19:29:24.718251Z","end":"2026-05-21T19:29:26.571783Z","steps":["trace[2018205657] 'process raft request' (duration: 1.246171457s)","trace[2018205657] 'compare' (duration: 606.984734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:26.571827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.718238Z","time spent":"1.85356854s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:26.571993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.107702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:26.572021Z","caller":"traceutil/trace.go:171","msg":"trace[1205663134] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:990; }","duration":"653.177664ms","start":"2026-05-21T19:29:25.918835Z","end":"2026-05-21T19:29:26.572012Z","steps":["trace[1205663134] 'agreement among raft nodes before linearized reading' (duration: 653.124033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:25.918806Z","time spent":"653.232226ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:29:26.572062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729684125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:29:26.5721Z","caller":"traceutil/trace.go:171","msg":"trace[497734069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"1.729737696s","start":"2026-05-21T19:29:24.842352Z","end":"2026-05-21T19:29:26.572089Z","steps":["trace[497734069] 'agreement among raft nodes before linearized reading' (duration: 1.729608223s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668401128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T19:29:26.572123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511789169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:29:26.572136Z","caller":"traceutil/trace.go:171","msg":"trace[8848903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"1.6684655s","start":"2026-05-21T19:29:24.903663Z","end":"2026-05-21T19:29:26.572129Z","steps":["trace[8848903] 'agreement among raft nodes before linearized reading' (duration: 1.668382878s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.57213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.842342Z","time spent":"1.729781138s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:29:26.572156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.903648Z","time spent":"1.66850396s","remote":"127.0.0.1:59322","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-05-21T19:29:26.57217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.730703609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:26.572153Z","caller":"traceutil/trace.go:171","msg":"trace[480737398] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:990; }","duration":"1.511857851s","start":"2026-05-21T19:29:25.060286Z","end":"2026-05-21T19:29:26.572144Z","steps":["trace[480737398] 'agreement among raft nodes before linearized reading' (duration: 1.51180226s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:26.572202Z","caller":"traceutil/trace.go:171","msg":"trace[180293165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"1.7307642s","start":"2026-05-21T19:29:24.841428Z","end":"2026-05-21T19:29:26.572192Z","steps":["trace[180293165] 'agreement among raft nodes before linearized reading' (duration: 1.730718859s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665023318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:29:26.572227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.841409Z","time spent":"1.730811392s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:29:26.57222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:25.06027Z","time spent":"1.511943704s","remote":"127.0.0.1:59470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:29:26.572246Z","caller":"traceutil/trace.go:171","msg":"trace[1063215212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"1.665067879s","start":"2026-05-21T19:29:24.907171Z","end":"2026-05-21T19:29:26.572238Z","steps":["trace[1063215212] 'agreement among raft nodes before linearized reading' (duration: 1.664996798s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.907159Z","time spent":"1.66510621s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:29:26.572317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697820062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T19:29:26.572382Z","caller":"traceutil/trace.go:171","msg":"trace[146861654] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:990; }","duration":"1.697914835s","start":"2026-05-21T19:29:24.874459Z","end":"2026-05-21T19:29:26.572374Z","steps":["trace[146861654] 'agreement among raft nodes before linearized reading' (duration: 1.697801862s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.874445Z","time spent":"1.697954365s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-05-21T19:29:26.57207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667570388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:29:26.572474Z","caller":"traceutil/trace.go:171","msg":"trace[2107134748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"1.668025579s","start":"2026-05-21T19:29:24.904441Z","end":"2026-05-21T19:29:26.572466Z","steps":["trace[2107134748] 'agreement among raft nodes before linearized reading' (duration: 1.667521257s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:26.572504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:24.90442Z","time spent":"1.6680711s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:29:28.819838Z","caller":"traceutil/trace.go:171","msg":"trace[630306350] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"168.620572ms","start":"2026-05-21T19:29:28.651193Z","end":"2026-05-21T19:29:28.819813Z","steps":["trace[630306350] 'process raft request' (duration: 168.482369ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:28.86633Z","caller":"traceutil/trace.go:171","msg":"trace[2014703508] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"213.489411ms","start":"2026-05-21T19:29:28.652823Z","end":"2026-05-21T19:29:28.866313Z","steps":["trace[2014703508] 'process raft request' (duration: 213.46333ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:28.866364Z","caller":"traceutil/trace.go:171","msg":"trace[6461042] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"214.571767ms","start":"2026-05-21T19:29:28.651772Z","end":"2026-05-21T19:29:28.866344Z","steps":["trace[6461042] 'process raft request' (duration: 214.391402ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:28.866422Z","caller":"traceutil/trace.go:171","msg":"trace[11481555] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"214.432934ms","start":"2026-05-21T19:29:28.651954Z","end":"2026-05-21T19:29:28.866387Z","steps":["trace[11481555] 'process raft request' (duration: 214.307451ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:34.760478Z","caller":"traceutil/trace.go:171","msg":"trace[1300193240] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"154.421454ms","start":"2026-05-21T19:29:34.606037Z","end":"2026-05-21T19:29:34.760458Z","steps":["trace[1300193240] 'process raft request' (duration: 154.272641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:49.342207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:49.843154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:50.343554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:50.783919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.957360211s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:29:50.784486Z","caller":"traceutil/trace.go:171","msg":"trace[1292630033] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"1.94292076s","start":"2026-05-21T19:29:48.841547Z","end":"2026-05-21T19:29:50.784468Z","steps":["trace[1292630033] 'read index received' (duration: 1.942604293s)","trace[1292630033] 'applied index is now lower than readState.Index' (duration: 313.317µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:50.78457Z","caller":"traceutil/trace.go:171","msg":"trace[1737142801] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"1.958144899s","start":"2026-05-21T19:29:48.826417Z","end":"2026-05-21T19:29:50.784562Z","steps":["trace[1737142801] 'process raft request' (duration: 1.957861773s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:50.784732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943154366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:50.784876Z","caller":"traceutil/trace.go:171","msg":"trace[600368490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.943376961s","start":"2026-05-21T19:29:48.841479Z","end":"2026-05-21T19:29:50.784856Z","steps":["trace[600368490] 'agreement among raft nodes before linearized reading' (duration: 1.943146276s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:50.784757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:48.826377Z","time spent":"1.958217061s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:50.784995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:48.841457Z","time spent":"1.943491174s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:29:51.284655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:51.785156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:52.285769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:29:52.392816Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.608621603s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:29:52.786936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000365598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-21T19:29:52.787234Z","caller":"traceutil/trace.go:171","msg":"trace[854489910] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000576253s","start":"2026-05-21T19:29:50.786503Z","end":"2026-05-21T19:29:52.787079Z","steps":["trace[854489910] 'agreement among raft nodes before linearized reading' (duration: 2.000393209s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:52.78733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:50.786487Z","time spent":"2.000831049s","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 19:29:52 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T19:29:53.197126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.062474ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:29:53.1975Z","caller":"traceutil/trace.go:171","msg":"trace[793148843] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"406.731767ms","start":"2026-05-21T19:29:52.790744Z","end":"2026-05-21T19:29:53.197475Z","steps":["trace[793148843] 'process raft request' (duration: 406.657345ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:53.197501Z","caller":"traceutil/trace.go:171","msg":"trace[1642921314] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.949194659s","start":"2026-05-21T19:29:51.248246Z","end":"2026-05-21T19:29:53.197441Z","steps":["trace[1642921314] 'process raft request' (duration: 1.949000195s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:53.197508Z","caller":"traceutil/trace.go:171","msg":"trace[328195491] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"2.412936943s","start":"2026-05-21T19:29:50.78454Z","end":"2026-05-21T19:29:53.197477Z","steps":["trace[328195491] 'read index received' (duration: 1.6085007s)","trace[328195491] 'applied index is now lower than readState.Index' (duration: 804.434243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:53.197614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.187312638s","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":"warn","ts":"2026-05-21T19:29:53.197641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:52.790724Z","time spent":"406.83643ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:29:53.19768Z","caller":"traceutil/trace.go:171","msg":"trace[1658956429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1065; }","duration":"3.187421221s","start":"2026-05-21T19:29:50.010244Z","end":"2026-05-21T19:29:53.197665Z","steps":["trace[1658956429] 'agreement among raft nodes before linearized reading' (duration: 3.187322748s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.197686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:51.248221Z","time spent":"1.949362003s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:53.197715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:50.010216Z","time spent":"3.187490392s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:29:53.19764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.572532348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:29:53.197849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.42765529s","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-05-21T19:29:53.197925Z","caller":"traceutil/trace.go:171","msg":"trace[994967199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"3.427780262s","start":"2026-05-21T19:29:49.77013Z","end":"2026-05-21T19:29:53.19791Z","steps":["trace[994967199] 'agreement among raft nodes before linearized reading' (duration: 3.427549937s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:53.197863Z","caller":"traceutil/trace.go:171","msg":"trace[274339027] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1065; }","duration":"3.572850796s","start":"2026-05-21T19:29:49.624995Z","end":"2026-05-21T19:29:53.197846Z","steps":["trace[274339027] 'agreement among raft nodes before linearized reading' (duration: 3.572499938s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.197977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.186566719s","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-05-21T19:29:53.197991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.770112Z","time spent":"3.427869355s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:29:53.198027Z","caller":"traceutil/trace.go:171","msg":"trace[1717733784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"4.18663635s","start":"2026-05-21T19:29:49.011375Z","end":"2026-05-21T19:29:53.198012Z","steps":["trace[1717733784] 'agreement among raft nodes before linearized reading' (duration: 4.186445035s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.198027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.624967Z","time spent":"3.573037031s","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:29:53.198072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.011364Z","time spent":"4.186699612s","remote":"127.0.0.1:59322","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-05-21T19:29:53.198097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.184804147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:29:53.19812Z","caller":"traceutil/trace.go:171","msg":"trace[610725230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"4.184843258s","start":"2026-05-21T19:29:49.01327Z","end":"2026-05-21T19:29:53.198114Z","steps":["trace[610725230] 'agreement among raft nodes before linearized reading' (duration: 4.184784087s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.198034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.193625855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:29:53.198167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.013263Z","time spent":"4.184879229s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:29:53.198173Z","caller":"traceutil/trace.go:171","msg":"trace[13161330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"4.193788519s","start":"2026-05-21T19:29:49.004364Z","end":"2026-05-21T19:29:53.198152Z","steps":["trace[13161330] 'agreement among raft nodes before linearized reading' (duration: 4.193617445s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.198227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.004346Z","time spent":"4.193866561s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:29:53.199836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.197050396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:29:53.199893Z","caller":"traceutil/trace.go:171","msg":"trace[1984952633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"4.197143598s","start":"2026-05-21T19:29:49.002736Z","end":"2026-05-21T19:29:53.199879Z","steps":["trace[1984952633] 'agreement among raft nodes before linearized reading' (duration: 4.196973434s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:53.199925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:49.002718Z","time spent":"4.19719954s","remote":"127.0.0.1:59322","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-05-21T19:29:54.244689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.490283ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-21T19:29:54.244796Z","caller":"traceutil/trace.go:171","msg":"trace[131136849] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"1.046894246s","start":"2026-05-21T19:29:53.197876Z","end":"2026-05-21T19:29:54.24477Z","steps":["trace[131136849] 'read index received' (duration: 251.377242ms)","trace[131136849] 'applied index is now lower than readState.Index' (duration: 795.515204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:54.244877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:52.790847Z","time spent":"1.454026833s","remote":"127.0.0.1:59136","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:29:54.245575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448607555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:54.245683Z","caller":"traceutil/trace.go:171","msg":"trace[506851243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"1.448762049s","start":"2026-05-21T19:29:52.796896Z","end":"2026-05-21T19:29:54.245658Z","steps":["trace[506851243] 'agreement among raft nodes before linearized reading' (duration: 1.448033452s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.245736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:52.796877Z","time spent":"1.448850362s","remote":"127.0.0.1:59054","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-05-21T19:29:54.249869Z","caller":"traceutil/trace.go:171","msg":"trace[1376308647] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"1.047541772s","start":"2026-05-21T19:29:53.202301Z","end":"2026-05-21T19:29:54.249843Z","steps":["trace[1376308647] 'process raft request' (duration: 1.047335907s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.25004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.202287Z","time spent":"1.047668754s","remote":"127.0.0.1:59322","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-05-21T19:29:54.250117Z","caller":"traceutil/trace.go:171","msg":"trace[944374508] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"1.047251605s","start":"2026-05-21T19:29:53.202843Z","end":"2026-05-21T19:29:54.250095Z","steps":["trace[944374508] 'process raft request' (duration: 1.046958818s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.250231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.202837Z","time spent":"1.047336696s","remote":"127.0.0.1:59322","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-05-21T19:29:54.250334Z","caller":"traceutil/trace.go:171","msg":"trace[1578486623] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"1.045854612s","start":"2026-05-21T19:29:53.204467Z","end":"2026-05-21T19:29:54.250322Z","steps":["trace[1578486623] 'process raft request' (duration: 1.045617176s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:54.250314Z","caller":"traceutil/trace.go:171","msg":"trace[743312033] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.045749999s","start":"2026-05-21T19:29:53.204547Z","end":"2026-05-21T19:29:54.250297Z","steps":["trace[743312033] 'process raft request' (duration: 1.045660867s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.250368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.20446Z","time spent":"1.045891313s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:54.250443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.204542Z","time spent":"1.045822711s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:29:54.250658Z","caller":"traceutil/trace.go:171","msg":"trace[1379321784] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"1.046915286s","start":"2026-05-21T19:29:53.203211Z","end":"2026-05-21T19:29:54.250126Z","steps":["trace[1379321784] 'process raft request' (duration: 1.046754083s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.250781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.203202Z","time spent":"1.047504571s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:54.719141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.42927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:29:54.719312Z","caller":"traceutil/trace.go:171","msg":"trace[2106197150] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1106; }","duration":"474.468908ms","start":"2026-05-21T19:29:54.244827Z","end":"2026-05-21T19:29:54.719296Z","steps":["trace[2106197150] 'read index received' (duration: 2.384577ms)","trace[2106197150] 'applied index is now lower than readState.Index' (duration: 472.081191ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:54.719394Z","caller":"traceutil/trace.go:171","msg":"trace[2008814609] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"741.433688ms","start":"2026-05-21T19:29:53.977938Z","end":"2026-05-21T19:29:54.719371Z","steps":["trace[2008814609] 'process raft request' (duration: 629.6941ms)","trace[2008814609] 'compare' (duration: 111.208825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:54.719573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.977911Z","time spent":"741.585622ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:29:54.71959Z","caller":"traceutil/trace.go:171","msg":"trace[1976721930] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"472.920131ms","start":"2026-05-21T19:29:54.246641Z","end":"2026-05-21T19:29:54.719561Z","steps":["trace[1976721930] 'process raft request' (duration: 472.591123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.719752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:54.246614Z","time spent":"473.053565ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:29:54.71993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.31047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:54.719987Z","caller":"traceutil/trace.go:171","msg":"trace[617347746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"473.403323ms","start":"2026-05-21T19:29:54.246568Z","end":"2026-05-21T19:29:54.719972Z","steps":["trace[617347746] 'agreement among raft nodes before linearized reading' (duration: 473.213188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.720887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:54.246549Z","time spent":"474.321904ms","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:29:54.719961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.883761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:54.721556Z","caller":"traceutil/trace.go:171","msg":"trace[662529136] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1072; }","duration":"755.426468ms","start":"2026-05-21T19:29:53.965979Z","end":"2026-05-21T19:29:54.721406Z","steps":["trace[662529136] 'agreement among raft nodes before linearized reading' (duration: 753.734908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:54.721719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:53.965952Z","time spent":"755.719145ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:29:55.059747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.47287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T19:29:55.059834Z","caller":"traceutil/trace.go:171","msg":"trace[1012694122] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1072; }","duration":"183.624754ms","start":"2026-05-21T19:29:54.87619Z","end":"2026-05-21T19:29:55.059815Z","steps":["trace[1012694122] 'range keys from in-memory index tree' (duration: 183.271526ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:56.437191Z","caller":"traceutil/trace.go:171","msg":"trace[523684949] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"179.654049ms","start":"2026-05-21T19:29:56.257505Z","end":"2026-05-21T19:29:56.437159Z","steps":["trace[523684949] 'process raft request' (duration: 179.405473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.116703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.590808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:29:57.117556Z","caller":"traceutil/trace.go:171","msg":"trace[725418624] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1117; }","duration":"273.760981ms","start":"2026-05-21T19:29:56.843771Z","end":"2026-05-21T19:29:57.117532Z","steps":["trace[725418624] 'read index received' (duration: 50.152µs)","trace[725418624] 'applied index is now lower than readState.Index' (duration: 273.709679ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:57.117963Z","caller":"traceutil/trace.go:171","msg":"trace[1508959555] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"859.144304ms","start":"2026-05-21T19:29:56.258787Z","end":"2026-05-21T19:29:57.117931Z","steps":["trace[1508959555] 'process raft request' (duration: 256.212105ms)","trace[1508959555] 'compare' (duration: 601.294961ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:57.117971Z","caller":"traceutil/trace.go:171","msg":"trace[1744773295] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"858.173591ms","start":"2026-05-21T19:29:56.259742Z","end":"2026-05-21T19:29:57.117915Z","steps":["trace[1744773295] 'process raft request' (duration: 857.672289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.118115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:56.258767Z","time spent":"859.268677ms","remote":"127.0.0.1:59322","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-05-21T19:29:57.118124Z","caller":"traceutil/trace.go:171","msg":"trace[677038783] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"857.135387ms","start":"2026-05-21T19:29:56.260937Z","end":"2026-05-21T19:29:57.118073Z","steps":["trace[677038783] 'process raft request' (duration: 856.534923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.118402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.630611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:29:57.118465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:56.260929Z","time spent":"857.303451ms","remote":"127.0.0.1:59322","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-05-21T19:29:57.118393Z","caller":"traceutil/trace.go:171","msg":"trace[731917558] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"858.920299ms","start":"2026-05-21T19:29:56.259404Z","end":"2026-05-21T19:29:57.118324Z","steps":["trace[731917558] 'process raft request' (duration: 857.617458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.1187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:56.259722Z","time spent":"858.350436ms","remote":"127.0.0.1:59322","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-05-21T19:29:57.118487Z","caller":"traceutil/trace.go:171","msg":"trace[492074048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"274.716033ms","start":"2026-05-21T19:29:56.843752Z","end":"2026-05-21T19:29:57.118468Z","steps":["trace[492074048] 'agreement among raft nodes before linearized reading' (duration: 273.867223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.118868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:56.25939Z","time spent":"859.216916ms","remote":"127.0.0.1:59322","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-05-21T19:29:57.74109Z","caller":"traceutil/trace.go:171","msg":"trace[641442829] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"434.639638ms","start":"2026-05-21T19:29:57.306412Z","end":"2026-05-21T19:29:57.741052Z","steps":["trace[641442829] 'process raft request' (duration: 434.233768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.741192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.390442ms","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-05-21T19:29:57.741246Z","caller":"traceutil/trace.go:171","msg":"trace[770597487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1080; }","duration":"373.497284ms","start":"2026-05-21T19:29:57.367737Z","end":"2026-05-21T19:29:57.741234Z","steps":["trace[770597487] 'agreement among raft nodes before linearized reading' (duration: 373.382201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:57.741301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:57.306358Z","time spent":"434.837602ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:29:57.741351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:57.367718Z","time spent":"373.567416ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:29:57.74102Z","caller":"traceutil/trace.go:171","msg":"trace[1338225185] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"373.220918ms","start":"2026-05-21T19:29:57.367775Z","end":"2026-05-21T19:29:57.740996Z","steps":["trace[1338225185] 'read index received' (duration: 372.87098ms)","trace[1338225185] 'applied index is now lower than readState.Index' (duration: 348.258µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:57.741949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.348084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:57.742023Z","caller":"traceutil/trace.go:171","msg":"trace[850046803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1080; }","duration":"285.468877ms","start":"2026-05-21T19:29:57.456532Z","end":"2026-05-21T19:29:57.742001Z","steps":["trace[850046803] 'agreement among raft nodes before linearized reading' (duration: 285.323973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:58.896014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052532869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:29:58.896101Z","caller":"traceutil/trace.go:171","msg":"trace[1806934738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"1.052633802s","start":"2026-05-21T19:29:57.843447Z","end":"2026-05-21T19:29:58.896081Z","steps":["trace[1806934738] 'range keys from in-memory index tree' (duration: 1.052434127s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:58.896141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:57.843425Z","time spent":"1.052707794s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:29:58.896238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.842964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:29:58.896307Z","caller":"traceutil/trace.go:171","msg":"trace[1833786145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"454.967717ms","start":"2026-05-21T19:29:58.441323Z","end":"2026-05-21T19:29:58.896291Z","steps":["trace[1833786145] 'range keys from in-memory index tree' (duration: 454.66975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:58.89634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:58.441301Z","time spent":"455.033039ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:29:59.326843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.787229ms","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-05-21T19:29:59.326924Z","caller":"traceutil/trace.go:171","msg":"trace[1189664482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"200.901821ms","start":"2026-05-21T19:29:59.126006Z","end":"2026-05-21T19:29:59.326908Z","steps":["trace[1189664482] 'range keys from in-memory index tree' (duration: 200.658905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:59.326967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.439821ms","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-05-21T19:29:59.326848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.192862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T19:29:59.32703Z","caller":"traceutil/trace.go:171","msg":"trace[1672882824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"203.530674ms","start":"2026-05-21T19:29:59.12348Z","end":"2026-05-21T19:29:59.327011Z","steps":["trace[1672882824] 'range keys from in-memory index tree' (duration: 203.331549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:59.327054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.133191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:29:59.327139Z","caller":"traceutil/trace.go:171","msg":"trace[133959054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"202.486428ms","start":"2026-05-21T19:29:59.124605Z","end":"2026-05-21T19:29:59.327091Z","steps":["trace[133959054] 'range keys from in-memory index tree' (duration: 202.052459ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:59.327288Z","caller":"traceutil/trace.go:171","msg":"trace[1216401866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"202.476278ms","start":"2026-05-21T19:29:59.124726Z","end":"2026-05-21T19:29:59.327202Z","steps":["trace[1216401866] 'range keys from in-memory index tree' (duration: 201.923265ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:59.440832Z","caller":"traceutil/trace.go:171","msg":"trace[2119356735] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"108.447449ms","start":"2026-05-21T19:29:59.33236Z","end":"2026-05-21T19:29:59.440807Z","steps":["trace[2119356735] 'process raft request' (duration: 108.276905ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:59.527199Z","caller":"traceutil/trace.go:171","msg":"trace[1620760361] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"194.120201ms","start":"2026-05-21T19:29:59.333052Z","end":"2026-05-21T19:29:59.527172Z","steps":["trace[1620760361] 'process raft request' (duration: 194.06984ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:59.527253Z","caller":"traceutil/trace.go:171","msg":"trace[1020752408] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"194.818207ms","start":"2026-05-21T19:29:59.332393Z","end":"2026-05-21T19:29:59.527211Z","steps":["trace[1020752408] 'process raft request' (duration: 194.556161ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:59.527261Z","caller":"traceutil/trace.go:171","msg":"trace[1171809699] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"194.662855ms","start":"2026-05-21T19:29:59.332556Z","end":"2026-05-21T19:29:59.527219Z","steps":["trace[1171809699] 'process raft request' (duration: 194.529951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:01.177161Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.429269919s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:30:01.177647Z","caller":"traceutil/trace.go:171","msg":"trace[1860902619] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.429932245s","start":"2026-05-21T19:29:59.747687Z","end":"2026-05-21T19:30:01.17762Z","steps":["trace[1860902619] 'process raft request' (duration: 1.429762931s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:01.177846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:59.747639Z","time spent":"1.430113789s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:01.37992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.61726ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:30:01.380032Z","caller":"traceutil/trace.go:171","msg":"trace[295545142] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1127; }","duration":"537.122526ms","start":"2026-05-21T19:30:00.842891Z","end":"2026-05-21T19:30:01.380013Z","steps":["trace[295545142] 'read index received' (duration: 334.532705ms)","trace[295545142] 'applied index is now lower than readState.Index' (duration: 202.588261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:01.38015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.250509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:01.380183Z","caller":"traceutil/trace.go:171","msg":"trace[419657980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"537.339891ms","start":"2026-05-21T19:30:00.842835Z","end":"2026-05-21T19:30:01.380174Z","steps":["trace[419657980] 'agreement among raft nodes before linearized reading' (duration: 537.267369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:01.380218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:00.842806Z","time spent":"537.403653ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:01.380294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.156525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:30:01.380347Z","caller":"traceutil/trace.go:171","msg":"trace[1931348862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1086; }","duration":"445.259778ms","start":"2026-05-21T19:30:00.935071Z","end":"2026-05-21T19:30:01.380331Z","steps":["trace[1931348862] 'agreement among raft nodes before linearized reading' (duration: 445.098254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:01.380321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.370781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:30:01.380381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:00.935039Z","time spent":"445.33487ms","remote":"127.0.0.1:59322","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-05-21T19:30:01.380417Z","caller":"traceutil/trace.go:171","msg":"trace[1483200545] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1086; }","duration":"134.535365ms","start":"2026-05-21T19:30:01.245856Z","end":"2026-05-21T19:30:01.380392Z","steps":["trace[1483200545] 'agreement among raft nodes before linearized reading' (duration: 134.34164ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:09.757869Z","caller":"traceutil/trace.go:171","msg":"trace[927655139] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"167.460412ms","start":"2026-05-21T19:30:09.59038Z","end":"2026-05-21T19:30:09.75784Z","steps":["trace[927655139] 'process raft request' (duration: 164.281327ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:14.943336Z","caller":"traceutil/trace.go:171","msg":"trace[1114235471] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"134.085815ms","start":"2026-05-21T19:30:14.809226Z","end":"2026-05-21T19:30:14.943312Z","steps":["trace[1114235471] 'process raft request' (duration: 110.935698ms)","trace[1114235471] 'compare' (duration: 22.817339ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:15.533663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.809383ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:15.5338Z","caller":"traceutil/trace.go:171","msg":"trace[269434914] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1182; }","duration":"215.951996ms","start":"2026-05-21T19:30:15.317824Z","end":"2026-05-21T19:30:15.533776Z","steps":["trace[269434914] 'range keys from in-memory index tree' (duration: 215.789582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:15.533835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.382748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:15.534044Z","caller":"traceutil/trace.go:171","msg":"trace[1692692274] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"414.233686ms","start":"2026-05-21T19:30:15.11979Z","end":"2026-05-21T19:30:15.534023Z","steps":["trace[1692692274] 'process raft request' (duration: 414.174214ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:15.534104Z","caller":"traceutil/trace.go:171","msg":"trace[997148155] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"511.089631ms","start":"2026-05-21T19:30:15.023002Z","end":"2026-05-21T19:30:15.534091Z","steps":["trace[997148155] 'process raft request' (duration: 49.365455ms)","trace[997148155] 'compare' (duration: 461.181344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:15.53413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.119754Z","time spent":"414.341558ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:15.534183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.022987Z","time spent":"511.140662ms","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:15.789555Z","caller":"traceutil/trace.go:171","msg":"trace[1190605785] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1229; }","duration":"318.732862ms","start":"2026-05-21T19:30:15.470794Z","end":"2026-05-21T19:30:15.789527Z","steps":["trace[1190605785] 'read index received' (duration: 3.897292ms)","trace[1190605785] 'applied index is now lower than readState.Index' (duration: 314.83181ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:15.789648Z","caller":"traceutil/trace.go:171","msg":"trace[137653409] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"546.728183ms","start":"2026-05-21T19:30:15.242885Z","end":"2026-05-21T19:30:15.789613Z","steps":["trace[137653409] 'process raft request' (duration: 515.411474ms)","trace[137653409] 'compare' (duration: 31.091804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:15.789786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.24286Z","time spent":"546.849056ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:15.789823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.999098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:30:15.789868Z","caller":"traceutil/trace.go:171","msg":"trace[2061639901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1185; }","duration":"319.103831ms","start":"2026-05-21T19:30:15.470751Z","end":"2026-05-21T19:30:15.789855Z","steps":["trace[2061639901] 'agreement among raft nodes before linearized reading' (duration: 318.910346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:15.789909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.470732Z","time spent":"319.169253ms","remote":"127.0.0.1:59322","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-05-21T19:30:15.789943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.849609ms","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-05-21T19:30:15.790024Z","caller":"traceutil/trace.go:171","msg":"trace[1345650935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1185; }","duration":"183.980522ms","start":"2026-05-21T19:30:15.606023Z","end":"2026-05-21T19:30:15.790004Z","steps":["trace[1345650935] 'agreement among raft nodes before linearized reading' (duration: 183.756267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:15.790036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.044934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:15.790077Z","caller":"traceutil/trace.go:171","msg":"trace[101936040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"184.107005ms","start":"2026-05-21T19:30:15.605957Z","end":"2026-05-21T19:30:15.790064Z","steps":["trace[101936040] 'agreement among raft nodes before linearized reading' (duration: 184.023453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:15.789938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.585695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:30:15.790125Z","caller":"traceutil/trace.go:171","msg":"trace[97929754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"264.82286ms","start":"2026-05-21T19:30:15.52529Z","end":"2026-05-21T19:30:15.790113Z","steps":["trace[97929754] 'agreement among raft nodes before linearized reading' (duration: 264.457611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:15.790113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.79707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-21T19:30:15.790208Z","caller":"traceutil/trace.go:171","msg":"trace[1052251302] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1185; }","duration":"253.924342ms","start":"2026-05-21T19:30:15.536261Z","end":"2026-05-21T19:30:15.790185Z","steps":["trace[1052251302] 'agreement among raft nodes before linearized reading' (duration: 253.782869ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:16.105746Z","caller":"traceutil/trace.go:171","msg":"trace[1486492583] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"309.473613ms","start":"2026-05-21T19:30:15.79625Z","end":"2026-05-21T19:30:16.105724Z","steps":["trace[1486492583] 'process raft request' (duration: 224.015246ms)","trace[1486492583] 'compare' (duration: 85.352174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:16.105876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.796228Z","time spent":"309.586696ms","remote":"127.0.0.1:59322","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-05-21T19:30:16.144809Z","caller":"traceutil/trace.go:171","msg":"trace[1097178897] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1232; }","duration":"303.755218ms","start":"2026-05-21T19:30:15.841019Z","end":"2026-05-21T19:30:16.144774Z","steps":["trace[1097178897] 'read index received' (duration: 179.26321ms)","trace[1097178897] 'applied index is now lower than readState.Index' (duration: 124.490518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:16.145091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.024065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:16.14514Z","caller":"traceutil/trace.go:171","msg":"trace[210059827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"304.135898ms","start":"2026-05-21T19:30:15.840991Z","end":"2026-05-21T19:30:16.145127Z","steps":["trace[210059827] 'agreement among raft nodes before linearized reading' (duration: 303.968824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.145185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.840978Z","time spent":"304.198019ms","remote":"127.0.0.1:59054","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-05-21T19:30:16.145627Z","caller":"traceutil/trace.go:171","msg":"trace[20837507] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"349.119729ms","start":"2026-05-21T19:30:15.79649Z","end":"2026-05-21T19:30:16.14561Z","steps":["trace[20837507] 'process raft request' (duration: 348.132166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.145757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.796481Z","time spent":"349.191921ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:16.145833Z","caller":"traceutil/trace.go:171","msg":"trace[2095331539] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"347.010589ms","start":"2026-05-21T19:30:15.798795Z","end":"2026-05-21T19:30:16.145806Z","steps":["trace[2095331539] 'process raft request' (duration: 345.895332ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:16.145954Z","caller":"traceutil/trace.go:171","msg":"trace[1155414146] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"349.639241ms","start":"2026-05-21T19:30:15.796299Z","end":"2026-05-21T19:30:16.145938Z","steps":["trace[1155414146] 'process raft request' (duration: 348.071604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.146055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.796281Z","time spent":"349.711123ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:16.14621Z","caller":"traceutil/trace.go:171","msg":"trace[1637233182] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"349.744003ms","start":"2026-05-21T19:30:15.796459Z","end":"2026-05-21T19:30:16.146203Z","steps":["trace[1637233182] 'process raft request' (duration: 348.108074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.145966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.798779Z","time spent":"347.109942ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:16.146252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.796441Z","time spent":"349.793215ms","remote":"127.0.0.1:59322","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-05-21T19:30:18.012493Z","caller":"traceutil/trace.go:171","msg":"trace[781015843] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"170.439932ms","start":"2026-05-21T19:30:17.842033Z","end":"2026-05-21T19:30:18.012473Z","steps":["trace[781015843] 'read index received' (duration: 170.242247ms)","trace[781015843] 'applied index is now lower than readState.Index' (duration: 196.905µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:18.012743Z","caller":"traceutil/trace.go:171","msg":"trace[211458773] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"216.209512ms","start":"2026-05-21T19:30:17.796525Z","end":"2026-05-21T19:30:18.012735Z","steps":["trace[211458773] 'process raft request' (duration: 215.820193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:18.012893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.856991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:18.012915Z","caller":"traceutil/trace.go:171","msg":"trace[197164143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"170.942233ms","start":"2026-05-21T19:30:17.841967Z","end":"2026-05-21T19:30:18.012909Z","steps":["trace[197164143] 'agreement among raft nodes before linearized reading' (duration: 170.893822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:19.342255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:30:19.843255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:30:20.099607Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.928244836s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:30:20.386899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:19.018423Z","time spent":"1.368470765s","remote":"127.0.0.1:56206","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:30:20.387178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.261259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:20.387223Z","caller":"traceutil/trace.go:171","msg":"trace[2017245197] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"2.215927574s","start":"2026-05-21T19:30:18.171284Z","end":"2026-05-21T19:30:20.387212Z","steps":["trace[2017245197] 'process raft request' (duration: 1.928575573s)","trace[2017245197] 'compare' (duration: 287.181327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:20.387251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.171268Z","time spent":"2.215970436s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:20.842192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000748637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-21T19:30:20.84233Z","caller":"traceutil/trace.go:171","msg":"trace[781448973] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000942451s","start":"2026-05-21T19:30:18.841365Z","end":"2026-05-21T19:30:20.842308Z","steps":["trace[781448973] 'agreement among raft nodes before linearized reading' (duration: 2.000769337s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:20.842386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.841347Z","time spent":"2.001028213s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 19:30:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T19:30:21.055055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.092067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:21.055501Z","caller":"traceutil/trace.go:171","msg":"trace[321485780] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"2.882583317s","start":"2026-05-21T19:30:18.172895Z","end":"2026-05-21T19:30:21.055479Z","steps":["trace[321485780] 'process raft request' (duration: 2.882247549s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:21.055519Z","caller":"traceutil/trace.go:171","msg":"trace[1691500817] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1239; }","duration":"2.214079511s","start":"2026-05-21T19:30:18.841419Z","end":"2026-05-21T19:30:21.055498Z","steps":["trace[1691500817] 'read index received' (duration: 1.258421428s)","trace[1691500817] 'applied index is now lower than readState.Index' (duration: 955.656673ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:21.055516Z","caller":"traceutil/trace.go:171","msg":"trace[672917288] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"1.037511044s","start":"2026-05-21T19:30:20.017978Z","end":"2026-05-21T19:30:21.05549Z","steps":["trace[672917288] 'process raft request' (duration: 1.037242588s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:21.055553Z","caller":"traceutil/trace.go:171","msg":"trace[692554447] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"2.882127867s","start":"2026-05-21T19:30:18.173392Z","end":"2026-05-21T19:30:21.05552Z","steps":["trace[692554447] 'process raft request' (duration: 2.881800309s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.055621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.172857Z","time spent":"2.88269785s","remote":"127.0.0.1:59322","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-05-21T19:30:21.055623Z","caller":"traceutil/trace.go:171","msg":"trace[588152959] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"2.883845007s","start":"2026-05-21T19:30:18.171741Z","end":"2026-05-21T19:30:21.055586Z","steps":["trace[588152959] 'process raft request' (duration: 2.376144386s)","trace[588152959] 'compare' (duration: 506.901893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:21.055657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:20.017953Z","time spent":"1.037628787s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:21.055657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.173366Z","time spent":"2.882230729s","remote":"127.0.0.1:59322","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-05-21T19:30:21.055775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.171733Z","time spent":"2.88396489s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:21.067059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.081886342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:30:21.067099Z","caller":"traceutil/trace.go:171","msg":"trace[900153939] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1197; }","duration":"2.081978044s","start":"2026-05-21T19:30:18.985112Z","end":"2026-05-21T19:30:21.06709Z","steps":["trace[900153939] 'agreement among raft nodes before linearized reading' (duration: 2.081899212s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:18.985085Z","time spent":"2.082044555s","remote":"127.0.0.1:59170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:21.067314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.440025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:21.067354Z","caller":"traceutil/trace.go:171","msg":"trace[2087787411] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1197; }","duration":"279.509356ms","start":"2026-05-21T19:30:20.787834Z","end":"2026-05-21T19:30:21.067343Z","steps":["trace[2087787411] 'agreement among raft nodes before linearized reading' (duration: 279.459415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353983063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:21.067448Z","caller":"traceutil/trace.go:171","msg":"trace[497354504] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1197; }","duration":"1.354062525s","start":"2026-05-21T19:30:19.713374Z","end":"2026-05-21T19:30:21.067437Z","steps":["trace[497354504] 'agreement among raft nodes before linearized reading' (duration: 1.353989333s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.855663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:30:21.067564Z","caller":"traceutil/trace.go:171","msg":"trace[720619539] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1197; }","duration":"202.11417ms","start":"2026-05-21T19:30:20.865431Z","end":"2026-05-21T19:30:21.067545Z","steps":["trace[720619539] 'agreement among raft nodes before linearized reading' (duration: 201.883004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.634186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T19:30:21.067626Z","caller":"traceutil/trace.go:171","msg":"trace[929900609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"895.712098ms","start":"2026-05-21T19:30:20.171904Z","end":"2026-05-21T19:30:21.067616Z","steps":["trace[929900609] 'agreement among raft nodes before linearized reading' (duration: 895.596976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:19.713353Z","time spent":"1.354154887s","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:21.067647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:20.171889Z","time spent":"895.753489ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:30:21.067522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435550208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:21.06772Z","caller":"traceutil/trace.go:171","msg":"trace[11616472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1197; }","duration":"1.435748983s","start":"2026-05-21T19:30:19.631962Z","end":"2026-05-21T19:30:21.067711Z","steps":["trace[11616472] 'agreement among raft nodes before linearized reading' (duration: 1.435535268s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.067746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:19.631923Z","time spent":"1.435815024s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:21.067979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.982709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:21.068026Z","caller":"traceutil/trace.go:171","msg":"trace[448620501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1197; }","duration":"197.085221ms","start":"2026-05-21T19:30:20.870926Z","end":"2026-05-21T19:30:21.068011Z","steps":["trace[448620501] 'agreement among raft nodes before linearized reading' (duration: 197.008499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:21.06963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.747493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:21.069764Z","caller":"traceutil/trace.go:171","msg":"trace[2029855226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"227.205701ms","start":"2026-05-21T19:30:20.842532Z","end":"2026-05-21T19:30:21.069737Z","steps":["trace[2029855226] 'agreement among raft nodes before linearized reading' (duration: 224.696222ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:32.939567Z","caller":"traceutil/trace.go:171","msg":"trace[1884325161] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"495.177546ms","start":"2026-05-21T19:30:32.444317Z","end":"2026-05-21T19:30:32.939494Z","steps":["trace[1884325161] 'process raft request' (duration: 494.966511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:32.940341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:32.444305Z","time spent":"495.757699ms","remote":"127.0.0.1:59322","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-05-21T19:30:33.223979Z","caller":"traceutil/trace.go:171","msg":"trace[3641903] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"110.822265ms","start":"2026-05-21T19:30:33.113126Z","end":"2026-05-21T19:30:33.223949Z","steps":["trace[3641903] 'process raft request' (duration: 110.542128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.224182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.609106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:30:33.224246Z","caller":"traceutil/trace.go:171","msg":"trace[380915122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"109.73578ms","start":"2026-05-21T19:30:33.1145Z","end":"2026-05-21T19:30:33.224236Z","steps":["trace[380915122] 'agreement among raft nodes before linearized reading' (duration: 109.519625ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:33.223964Z","caller":"traceutil/trace.go:171","msg":"trace[2091827608] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"109.352481ms","start":"2026-05-21T19:30:33.11455Z","end":"2026-05-21T19:30:33.223902Z","steps":["trace[2091827608] 'read index received' (duration: 109.000672ms)","trace[2091827608] 'applied index is now lower than readState.Index' (duration: 350.949µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:33.789274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.103398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:33.789448Z","caller":"traceutil/trace.go:171","msg":"trace[901872001] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"557.576539ms","start":"2026-05-21T19:30:33.231849Z","end":"2026-05-21T19:30:33.789425Z","steps":["trace[901872001] 'process raft request' (duration: 44.248105ms)","trace[901872001] 'compare' (duration: 512.878053ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:33.789506Z","caller":"traceutil/trace.go:171","msg":"trace[823180668] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"555.787706ms","start":"2026-05-21T19:30:33.233704Z","end":"2026-05-21T19:30:33.789491Z","steps":["trace[823180668] 'process raft request' (duration: 555.742985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.789551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:33.231828Z","time spent":"557.669041ms","remote":"127.0.0.1:59322","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-05-21T19:30:33.7896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:33.233697Z","time spent":"555.870818ms","remote":"127.0.0.1:59322","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-05-21T19:30:33.789764Z","caller":"traceutil/trace.go:171","msg":"trace[584078160] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"557.300602ms","start":"2026-05-21T19:30:33.232457Z","end":"2026-05-21T19:30:33.789758Z","steps":["trace[584078160] 'process raft request' (duration: 556.906532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.789793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:33.232427Z","time spent":"557.351303ms","remote":"127.0.0.1:59322","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-05-21T19:30:33.809473Z","caller":"traceutil/trace.go:171","msg":"trace[1504205391] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"183.392128ms","start":"2026-05-21T19:30:33.62605Z","end":"2026-05-21T19:30:33.809443Z","steps":["trace[1504205391] 'process raft request' (duration: 183.222344ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:36.019211Z","caller":"traceutil/trace.go:171","msg":"trace[666836474] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"220.590545ms","start":"2026-05-21T19:30:35.798597Z","end":"2026-05-21T19:30:36.019187Z","steps":["trace[666836474] 'process raft request' (duration: 220.547824ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:36.019507Z","caller":"traceutil/trace.go:171","msg":"trace[790808226] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"220.940753ms","start":"2026-05-21T19:30:35.798496Z","end":"2026-05-21T19:30:36.019437Z","steps":["trace[790808226] 'process raft request' (duration: 220.513813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:36.844622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.562188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:36.844706Z","caller":"traceutil/trace.go:171","msg":"trace[1524636923] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1255; }","duration":"479.684051ms","start":"2026-05-21T19:30:36.365001Z","end":"2026-05-21T19:30:36.844685Z","steps":["trace[1524636923] 'count revisions from in-memory index tree' (duration: 479.430944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:36.844745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:36.364986Z","time spent":"479.748782ms","remote":"127.0.0.1:59368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:30:37.470717Z","caller":"traceutil/trace.go:171","msg":"trace[2081034976] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"515.188708ms","start":"2026-05-21T19:30:36.955498Z","end":"2026-05-21T19:30:37.470687Z","steps":["trace[2081034976] 'process raft request' (duration: 515.012244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.470883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:36.955482Z","time spent":"515.317351ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:38.239771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.952707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:38.239872Z","caller":"traceutil/trace.go:171","msg":"trace[140268130] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"971.912687ms","start":"2026-05-21T19:30:37.267943Z","end":"2026-05-21T19:30:38.239856Z","steps":["trace[140268130] 'read index received' (duration: 202.628632ms)","trace[140268130] 'applied index is now lower than readState.Index' (duration: 769.282975ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:38.239959Z","caller":"traceutil/trace.go:171","msg":"trace[1491136621] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"1.004901365s","start":"2026-05-21T19:30:37.235046Z","end":"2026-05-21T19:30:38.239947Z","steps":["trace[1491136621] 'process raft request' (duration: 499.690422ms)","trace[1491136621] 'compare' (duration: 504.817364ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:38.240031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.235028Z","time spent":"1.004964736s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:38.240399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.45822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:30:38.240458Z","caller":"traceutil/trace.go:171","msg":"trace[1141348098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"972.502891ms","start":"2026-05-21T19:30:37.267929Z","end":"2026-05-21T19:30:38.240432Z","steps":["trace[1141348098] 'agreement among raft nodes before linearized reading' (duration: 972.303076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.240457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.543398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:30:38.240498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.267882Z","time spent":"972.608663ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:30:38.240544Z","caller":"traceutil/trace.go:171","msg":"trace[1868142357] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1257; }","duration":"685.690462ms","start":"2026-05-21T19:30:37.55483Z","end":"2026-05-21T19:30:38.240521Z","steps":["trace[1868142357] 'agreement among raft nodes before linearized reading' (duration: 685.542948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.240608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.554814Z","time spent":"685.779304ms","remote":"127.0.0.1:34268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:38.240631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.74072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:30:38.240637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.774992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:30:38.240664Z","caller":"traceutil/trace.go:171","msg":"trace[1581436454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"398.812522ms","start":"2026-05-21T19:30:37.841843Z","end":"2026-05-21T19:30:38.240656Z","steps":["trace[1581436454] 'agreement among raft nodes before linearized reading' (duration: 398.754191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.240693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.841821Z","time spent":"398.867913ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:38.240734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.844084ms","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-05-21T19:30:38.240763Z","caller":"traceutil/trace.go:171","msg":"trace[1925609319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"215.906485ms","start":"2026-05-21T19:30:38.024848Z","end":"2026-05-21T19:30:38.240755Z","steps":["trace[1925609319] 'agreement among raft nodes before linearized reading' (duration: 215.834264ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:38.240665Z","caller":"traceutil/trace.go:171","msg":"trace[900952253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"215.830694ms","start":"2026-05-21T19:30:38.024826Z","end":"2026-05-21T19:30:38.240657Z","steps":["trace[900952253] 'agreement among raft nodes before linearized reading' (duration: 215.750542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.240776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.140178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:30:38.240786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.695729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:30:38.240831Z","caller":"traceutil/trace.go:171","msg":"trace[610585204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"423.784691ms","start":"2026-05-21T19:30:37.817035Z","end":"2026-05-21T19:30:38.24082Z","steps":["trace[610585204] 'agreement among raft nodes before linearized reading' (duration: 423.654438ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:38.240829Z","caller":"traceutil/trace.go:171","msg":"trace[2135449166] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1257; }","duration":"118.280422ms","start":"2026-05-21T19:30:38.122534Z","end":"2026-05-21T19:30:38.240815Z","steps":["trace[2135449166] 'agreement among raft nodes before linearized reading' (duration: 118.130248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.240862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.817016Z","time spent":"423.839673ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:30:38.964922Z","caller":"traceutil/trace.go:171","msg":"trace[925126653] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"720.73432ms","start":"2026-05-21T19:30:38.244154Z","end":"2026-05-21T19:30:38.964889Z","steps":["trace[925126653] 'process raft request' (duration: 720.482404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.965129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:38.244148Z","time spent":"720.875153ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:38.965283Z","caller":"traceutil/trace.go:171","msg":"trace[739854453] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"721.080567ms","start":"2026-05-21T19:30:38.244173Z","end":"2026-05-21T19:30:38.965253Z","steps":["trace[739854453] 'process raft request' (duration: 720.679278ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:38.965285Z","caller":"traceutil/trace.go:171","msg":"trace[582165464] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"719.902829ms","start":"2026-05-21T19:30:38.245349Z","end":"2026-05-21T19:30:38.965252Z","steps":["trace[582165464] 'process raft request' (duration: 719.768626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:38.965428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:38.244157Z","time spent":"721.184199ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:38.965443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:38.245336Z","time spent":"720.021163ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:39.856362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.268651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:39.856566Z","caller":"traceutil/trace.go:171","msg":"trace[1275117076] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"378.763949ms","start":"2026-05-21T19:30:39.477777Z","end":"2026-05-21T19:30:39.856541Z","steps":["trace[1275117076] 'process raft request' (duration: 271.218031ms)","trace[1275117076] 'compare' (duration: 107.076877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:39.856669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:39.477764Z","time spent":"378.859121ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:40.783715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:30:41.284526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:30:41.784675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197368589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:30:41.803486Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.557482506s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:30:41.803969Z","caller":"traceutil/trace.go:171","msg":"trace[1052843618] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.55807503s","start":"2026-05-21T19:30:40.245865Z","end":"2026-05-21T19:30:41.80394Z","steps":["trace[1052843618] 'process raft request' (duration: 1.557852294s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:41.804153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:40.245839Z","time spent":"1.558223764s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:42.343341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.733464ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:30:42.343441Z","caller":"traceutil/trace.go:171","msg":"trace[1804669806] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1314; }","duration":"2.062383691s","start":"2026-05-21T19:30:40.281038Z","end":"2026-05-21T19:30:42.343422Z","steps":["trace[1804669806] 'read index received' (duration: 1.522711395s)","trace[1804669806] 'applied index is now lower than readState.Index' (duration: 539.670786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:42.343728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062673289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T19:30:42.343769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.204609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:42.34378Z","caller":"traceutil/trace.go:171","msg":"trace[1634893026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"2.062777911s","start":"2026-05-21T19:30:40.280987Z","end":"2026-05-21T19:30:42.343765Z","steps":["trace[1634893026] 'agreement among raft nodes before linearized reading' (duration: 2.062564486s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:42.343822Z","caller":"traceutil/trace.go:171","msg":"trace[2014245270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"504.297401ms","start":"2026-05-21T19:30:41.839506Z","end":"2026-05-21T19:30:42.343804Z","steps":["trace[2014245270] 'agreement among raft nodes before linearized reading' (duration: 504.22866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.34383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:40.280965Z","time spent":"2.062851062s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:30:42.343855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:41.839443Z","time spent":"504.404294ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:42.343833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375131492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T19:30:42.343869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.34673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:82462"} {"level":"info","ts":"2026-05-21T19:30:42.343909Z","caller":"traceutil/trace.go:171","msg":"trace[1277662737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"1.375248845s","start":"2026-05-21T19:30:40.968641Z","end":"2026-05-21T19:30:42.34389Z","steps":["trace[1277662737] 'agreement among raft nodes before linearized reading' (duration: 1.375004509s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.343869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.03022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-21T19:30:42.343958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:40.968628Z","time spent":"1.375321857s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:30:42.343954Z","caller":"traceutil/trace.go:171","msg":"trace[1698229271] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1264; }","duration":"948.524774ms","start":"2026-05-21T19:30:41.395408Z","end":"2026-05-21T19:30:42.343933Z","steps":["trace[1698229271] 'agreement among raft nodes before linearized reading' (duration: 948.247558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.343975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.589102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:30:42.344024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:41.395389Z","time spent":"948.619257ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":82485,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-21T19:30:42.344057Z","caller":"traceutil/trace.go:171","msg":"trace[634991118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"483.722856ms","start":"2026-05-21T19:30:41.860315Z","end":"2026-05-21T19:30:42.344038Z","steps":["trace[634991118] 'agreement among raft nodes before linearized reading' (duration: 483.386628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.344104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:41.860303Z","time spent":"483.792117ms","remote":"127.0.0.1:59322","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-05-21T19:30:42.344113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374940098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:30:42.344007Z","caller":"traceutil/trace.go:171","msg":"trace[299803403] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1264; }","duration":"950.209135ms","start":"2026-05-21T19:30:41.393778Z","end":"2026-05-21T19:30:42.343987Z","steps":["trace[299803403] 'agreement among raft nodes before linearized reading' (duration: 949.993289ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:42.344172Z","caller":"traceutil/trace.go:171","msg":"trace[75409709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"1.37502642s","start":"2026-05-21T19:30:40.969133Z","end":"2026-05-21T19:30:42.344159Z","steps":["trace[75409709] 'agreement among raft nodes before linearized reading' (duration: 1.37463447s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.344182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:41.393762Z","time spent":"950.409339ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-21T19:30:42.344216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:40.969118Z","time spent":"1.375086432s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:30:42.344299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374852356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:30:42.344342Z","caller":"traceutil/trace.go:171","msg":"trace[1402464025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"1.374939398s","start":"2026-05-21T19:30:40.969392Z","end":"2026-05-21T19:30:42.344332Z","steps":["trace[1402464025] 'agreement among raft nodes before linearized reading' (duration: 1.374285702s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.34437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:40.969361Z","time spent":"1.375000989s","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:30:42.458777Z","caller":"traceutil/trace.go:171","msg":"trace[2077893860] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"109.129286ms","start":"2026-05-21T19:30:42.349622Z","end":"2026-05-21T19:30:42.458751Z","steps":["trace[2077893860] 'read index received' (duration: 109.013133ms)","trace[2077893860] 'applied index is now lower than readState.Index' (duration: 113.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:42.45887Z","caller":"traceutil/trace.go:171","msg":"trace[787754277] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"109.258248ms","start":"2026-05-21T19:30:42.349587Z","end":"2026-05-21T19:30:42.458845Z","steps":["trace[787754277] 'process raft request' (duration: 109.001102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.459245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.620657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:82462"} {"level":"info","ts":"2026-05-21T19:30:42.459323Z","caller":"traceutil/trace.go:171","msg":"trace[1090547017] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1265; }","duration":"109.7362ms","start":"2026-05-21T19:30:42.34957Z","end":"2026-05-21T19:30:42.459306Z","steps":["trace[1090547017] 'agreement among raft nodes before linearized reading' (duration: 109.30736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.953698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.684893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:42.953912Z","caller":"traceutil/trace.go:171","msg":"trace[2013031849] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"603.759708ms","start":"2026-05-21T19:30:42.350093Z","end":"2026-05-21T19:30:42.953853Z","steps":["trace[2013031849] 'process raft request' (duration: 203.823899ms)","trace[2013031849] 'compare' (duration: 399.56644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:42.954081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:42.350081Z","time spent":"603.907702ms","remote":"127.0.0.1:59322","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-05-21T19:30:42.954685Z","caller":"traceutil/trace.go:171","msg":"trace[1930336955] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1317; }","duration":"495.824061ms","start":"2026-05-21T19:30:42.458844Z","end":"2026-05-21T19:30:42.954668Z","steps":["trace[1930336955] 'read index received' (duration: 95.084644ms)","trace[1930336955] 'applied index is now lower than readState.Index' (duration: 400.738407ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:42.954808Z","caller":"traceutil/trace.go:171","msg":"trace[1426147841] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"604.645419ms","start":"2026-05-21T19:30:42.350145Z","end":"2026-05-21T19:30:42.95479Z","steps":["trace[1426147841] 'process raft request' (duration: 603.643315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.95491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:42.350133Z","time spent":"604.733471ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:42.954966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.10113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-21T19:30:42.95503Z","caller":"traceutil/trace.go:171","msg":"trace[132705489] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1269; }","duration":"605.186432ms","start":"2026-05-21T19:30:42.349827Z","end":"2026-05-21T19:30:42.955014Z","steps":["trace[132705489] 'agreement among raft nodes before linearized reading' (duration: 605.08107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.955149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:42.349813Z","time spent":"605.320445ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-21T19:30:42.955508Z","caller":"traceutil/trace.go:171","msg":"trace[1542698624] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"604.612628ms","start":"2026-05-21T19:30:42.350883Z","end":"2026-05-21T19:30:42.955496Z","steps":["trace[1542698624] 'process raft request' (duration: 603.740928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.955579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:42.350875Z","time spent":"604.67005ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:42.955724Z","caller":"traceutil/trace.go:171","msg":"trace[1667944862] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"605.554191ms","start":"2026-05-21T19:30:42.35016Z","end":"2026-05-21T19:30:42.955714Z","steps":["trace[1667944862] 'process raft request' (duration: 603.680337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:42.955157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.345755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:30:42.955784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:42.350152Z","time spent":"605.603312ms","remote":"127.0.0.1:59322","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-05-21T19:30:42.955789Z","caller":"traceutil/trace.go:171","msg":"trace[320502061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"114.00262ms","start":"2026-05-21T19:30:42.841768Z","end":"2026-05-21T19:30:42.95577Z","steps":["trace[320502061] 'agreement among raft nodes before linearized reading' (duration: 113.359635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.051422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.668329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:44.051587Z","caller":"traceutil/trace.go:171","msg":"trace[1637590742] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"531.203327ms","start":"2026-05-21T19:30:43.520372Z","end":"2026-05-21T19:30:44.051575Z","steps":["trace[1637590742] 'process raft request' (duration: 531.144655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.051627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:43.520355Z","time spent":"531.254518ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:30:44.051829Z","caller":"traceutil/trace.go:171","msg":"trace[1020672428] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"540.003243ms","start":"2026-05-21T19:30:43.511804Z","end":"2026-05-21T19:30:44.051808Z","steps":["trace[1020672428] 'process raft request' (duration: 154.885954ms)","trace[1020672428] 'compare' (duration: 384.325821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:44.051927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:43.511784Z","time spent":"540.088566ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:44.548288Z","caller":"traceutil/trace.go:171","msg":"trace[2025639898] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"737.907004ms","start":"2026-05-21T19:30:43.810351Z","end":"2026-05-21T19:30:44.548258Z","steps":["trace[2025639898] 'process raft request' (duration: 721.315772ms)","trace[2025639898] 'compare' (duration: 15.703081ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:44.548522Z","caller":"traceutil/trace.go:171","msg":"trace[977251826] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"597.959192ms","start":"2026-05-21T19:30:43.950553Z","end":"2026-05-21T19:30:44.548512Z","steps":["trace[977251826] 'process raft request' (duration: 597.747347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.548614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:43.950528Z","time spent":"598.035893ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:44.548398Z","caller":"traceutil/trace.go:171","msg":"trace[347851348] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1324; }","duration":"706.011412ms","start":"2026-05-21T19:30:43.842365Z","end":"2026-05-21T19:30:44.548376Z","steps":["trace[347851348] 'read index received' (duration: 148.858643ms)","trace[347851348] 'applied index is now lower than readState.Index' (duration: 557.151729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:44.548485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.106724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:44.548767Z","caller":"traceutil/trace.go:171","msg":"trace[669189661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"706.414841ms","start":"2026-05-21T19:30:43.84233Z","end":"2026-05-21T19:30:44.548744Z","steps":["trace[669189661] 'agreement among raft nodes before linearized reading' (duration: 706.113494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.548841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:43.842315Z","time spent":"706.502703ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:44.549008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:43.810331Z","time spent":"738.191721ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:44.568651Z","caller":"traceutil/trace.go:171","msg":"trace[742457627] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"513.062238ms","start":"2026-05-21T19:30:44.055569Z","end":"2026-05-21T19:30:44.568631Z","steps":["trace[742457627] 'process raft request' (duration: 512.895384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.56876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:44.055553Z","time spent":"513.16165ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:30:44.568741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.487687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:44.568831Z","caller":"traceutil/trace.go:171","msg":"trace[1473971701] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1277; }","duration":"418.633469ms","start":"2026-05-21T19:30:44.150175Z","end":"2026-05-21T19:30:44.568809Z","steps":["trace[1473971701] 'agreement among raft nodes before linearized reading' (duration: 418.458045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.568906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:44.150153Z","time spent":"418.735252ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:44.568848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.616891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:30:44.56895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:44.569024Z","caller":"traceutil/trace.go:171","msg":"trace[1103174780] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1277; }","duration":"516.780566ms","start":"2026-05-21T19:30:44.05219Z","end":"2026-05-21T19:30:44.56897Z","steps":["trace[1103174780] 'agreement among raft nodes before linearized reading' (duration: 516.52154ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:44.569066Z","caller":"traceutil/trace.go:171","msg":"trace[1151989534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"105.50242ms","start":"2026-05-21T19:30:44.46351Z","end":"2026-05-21T19:30:44.569012Z","steps":["trace[1151989534] 'agreement among raft nodes before linearized reading' (duration: 105.10279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:44.569169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:44.052179Z","time spent":"516.925689ms","remote":"127.0.0.1:59372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:30:45.152648Z","caller":"traceutil/trace.go:171","msg":"trace[1467802975] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"138.864817ms","start":"2026-05-21T19:30:45.013759Z","end":"2026-05-21T19:30:45.152624Z","steps":["trace[1467802975] 'process raft request' (duration: 138.800205ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:45.152889Z","caller":"traceutil/trace.go:171","msg":"trace[1457295468] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"139.208415ms","start":"2026-05-21T19:30:45.013669Z","end":"2026-05-21T19:30:45.152878Z","steps":["trace[1457295468] 'process raft request' (duration: 138.718523ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:45.153103Z","caller":"traceutil/trace.go:171","msg":"trace[758612556] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"139.366289ms","start":"2026-05-21T19:30:45.013708Z","end":"2026-05-21T19:30:45.153075Z","steps":["trace[758612556] 'process raft request' (duration: 138.816326ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:45.153181Z","caller":"traceutil/trace.go:171","msg":"trace[2076269960] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"138.434507ms","start":"2026-05-21T19:30:45.014702Z","end":"2026-05-21T19:30:45.153136Z","steps":["trace[2076269960] 'process raft request' (duration: 137.889394ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:46.348915Z","caller":"traceutil/trace.go:171","msg":"trace[875417715] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"120.75486ms","start":"2026-05-21T19:30:46.228135Z","end":"2026-05-21T19:30:46.348889Z","steps":["trace[875417715] 'process raft request' (duration: 120.594616ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:49.2222Z","caller":"traceutil/trace.go:171","msg":"trace[1339579786] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"161.961542ms","start":"2026-05-21T19:30:49.060211Z","end":"2026-05-21T19:30:49.222173Z","steps":["trace[1339579786] 'process raft request' (duration: 161.755487ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:50.911943Z","caller":"traceutil/trace.go:171","msg":"trace[919905333] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1376; }","duration":"275.156933ms","start":"2026-05-21T19:30:50.636767Z","end":"2026-05-21T19:30:50.911924Z","steps":["trace[919905333] 'read index received' (duration: 272.924041ms)","trace[919905333] 'applied index is now lower than readState.Index' (duration: 2.231942ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:50.912087Z","caller":"traceutil/trace.go:171","msg":"trace[1649473583] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"348.105315ms","start":"2026-05-21T19:30:50.563924Z","end":"2026-05-21T19:30:50.912029Z","steps":["trace[1649473583] 'process raft request' (duration: 347.90409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:50.912454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:50.563902Z","time spent":"348.372891ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:50.912781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.980303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:50.912887Z","caller":"traceutil/trace.go:171","msg":"trace[1394873493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"276.146637ms","start":"2026-05-21T19:30:50.636717Z","end":"2026-05-21T19:30:50.912863Z","steps":["trace[1394873493] 'agreement among raft nodes before linearized reading' (duration: 275.86505ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:51.066876Z","caller":"traceutil/trace.go:171","msg":"trace[896660764] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"149.894568ms","start":"2026-05-21T19:30:50.916952Z","end":"2026-05-21T19:30:51.066846Z","steps":["trace[896660764] 'process raft request' (duration: 111.45923ms)","trace[896660764] 'compare' (duration: 38.223013ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:53.067367Z","caller":"traceutil/trace.go:171","msg":"trace[404603007] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"144.626003ms","start":"2026-05-21T19:30:52.92272Z","end":"2026-05-21T19:30:53.067346Z","steps":["trace[404603007] 'process raft request' (duration: 122.992773ms)","trace[404603007] 'compare' (duration: 21.532728ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:53.581308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.944056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:53.581393Z","caller":"traceutil/trace.go:171","msg":"trace[395301734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"102.086489ms","start":"2026-05-21T19:30:53.479288Z","end":"2026-05-21T19:30:53.581374Z","steps":["trace[395301734] 'range keys from in-memory index tree' (duration: 101.841513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:53.58174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.000246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-21T19:30:53.581773Z","caller":"traceutil/trace.go:171","msg":"trace[1905894358] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"146.065637ms","start":"2026-05-21T19:30:53.435697Z","end":"2026-05-21T19:30:53.581763Z","steps":["trace[1905894358] 'range keys from in-memory index tree' (duration: 145.79448ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:53.775753Z","caller":"traceutil/trace.go:171","msg":"trace[1198215699] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"189.589504ms","start":"2026-05-21T19:30:53.586131Z","end":"2026-05-21T19:30:53.775721Z","steps":["trace[1198215699] 'process raft request' (duration: 107.993319ms)","trace[1198215699] 'compare' (duration: 81.468712ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:09.260599Z","caller":"traceutil/trace.go:171","msg":"trace[1054231402] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"104.69189ms","start":"2026-05-21T19:31:09.155871Z","end":"2026-05-21T19:31:09.260563Z","steps":["trace[1054231402] 'process raft request' (duration: 33.46063ms)","trace[1054231402] 'compare' (duration: 71.034576ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:13.524279Z","caller":"traceutil/trace.go:171","msg":"trace[1910316093] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1503; }","duration":"110.312644ms","start":"2026-05-21T19:31:13.413939Z","end":"2026-05-21T19:31:13.524252Z","steps":["trace[1910316093] 'read index received' (duration: 89.638916ms)","trace[1910316093] 'applied index is now lower than readState.Index' (duration: 20.672227ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:13.524434Z","caller":"traceutil/trace.go:171","msg":"trace[695574625] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"112.993357ms","start":"2026-05-21T19:31:13.411406Z","end":"2026-05-21T19:31:13.524399Z","steps":["trace[695574625] 'process raft request' (duration: 91.95164ms)","trace[695574625] 'compare' (duration: 20.700829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:31:13.524582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.618211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:31:13.524633Z","caller":"traceutil/trace.go:171","msg":"trace[1161248287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1446; }","duration":"110.703892ms","start":"2026-05-21T19:31:13.413914Z","end":"2026-05-21T19:31:13.524618Z","steps":["trace[1161248287] 'agreement among raft nodes before linearized reading' (duration: 110.501757ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:13.679655Z","caller":"traceutil/trace.go:171","msg":"trace[388855449] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"150.612434ms","start":"2026-05-21T19:31:13.529005Z","end":"2026-05-21T19:31:13.679617Z","steps":["trace[388855449] 'process raft request' (duration: 115.399753ms)","trace[388855449] 'compare' (duration: 34.884853ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:13.679814Z","caller":"traceutil/trace.go:171","msg":"trace[542618370] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"150.393809ms","start":"2026-05-21T19:31:13.529386Z","end":"2026-05-21T19:31:13.67978Z","steps":["trace[542618370] 'process raft request' (duration: 150.052261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.061174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765538197369341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:31:46.097665Z","caller":"traceutil/trace.go:171","msg":"trace[1572403904] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"559.847562ms","start":"2026-05-21T19:31:45.537793Z","end":"2026-05-21T19:31:46.09764Z","steps":["trace[1572403904] 'process raft request' (duration: 559.801201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.097926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:45.53777Z","time spent":"559.972915ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:31:46.098384Z","caller":"traceutil/trace.go:171","msg":"trace[734305928] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"595.479763ms","start":"2026-05-21T19:31:45.502881Z","end":"2026-05-21T19:31:46.098361Z","steps":["trace[734305928] 'process raft request' (duration: 594.465389ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:46.098701Z","caller":"traceutil/trace.go:171","msg":"trace[969608772] linearizableReadLoop","detail":"{readStateIndex:1659; appliedIndex:1659; }","duration":"537.800612ms","start":"2026-05-21T19:31:45.560876Z","end":"2026-05-21T19:31:46.098676Z","steps":["trace[969608772] 'read index received' (duration: 537.790592ms)","trace[969608772] 'applied index is now lower than readState.Index' (duration: 7.91µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:31:46.098766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:45.502855Z","time spent":"595.580546ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:31:46.098928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.045848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:31:46.098963Z","caller":"traceutil/trace.go:171","msg":"trace[1162689587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1594; }","duration":"538.13045ms","start":"2026-05-21T19:31:45.560821Z","end":"2026-05-21T19:31:46.098952Z","steps":["trace[1162689587] 'agreement among raft nodes before linearized reading' (duration: 537.981896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.099003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:45.560794Z","time spent":"538.198961ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:31:46.099098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.899828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:31:46.099143Z","caller":"traceutil/trace.go:171","msg":"trace[1662977830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1594; }","duration":"238.959349ms","start":"2026-05-21T19:31:45.86017Z","end":"2026-05-21T19:31:46.099129Z","steps":["trace[1662977830] 'agreement among raft nodes before linearized reading' (duration: 238.905298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.098951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.230733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:31:46.099212Z","caller":"traceutil/trace.go:171","msg":"trace[2050454709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1594; }","duration":"269.560322ms","start":"2026-05-21T19:31:45.829642Z","end":"2026-05-21T19:31:46.099202Z","steps":["trace[2050454709] 'agreement among raft nodes before linearized reading' (duration: 269.212163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.099235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.043113ms","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-05-21T19:31:46.099272Z","caller":"traceutil/trace.go:171","msg":"trace[1242751460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1594; }","duration":"245.079414ms","start":"2026-05-21T19:31:45.854182Z","end":"2026-05-21T19:31:46.099262Z","steps":["trace[1242751460] 'agreement among raft nodes before linearized reading' (duration: 244.964191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.099369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.543381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:31:46.099389Z","caller":"traceutil/trace.go:171","msg":"trace[1871683664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1594; }","duration":"244.580102ms","start":"2026-05-21T19:31:45.854804Z","end":"2026-05-21T19:31:46.099384Z","steps":["trace[1871683664] 'agreement among raft nodes before linearized reading' (duration: 244.522971ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:46.541301Z","caller":"traceutil/trace.go:171","msg":"trace[1481994488] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"436.564122ms","start":"2026-05-21T19:31:46.104707Z","end":"2026-05-21T19:31:46.541271Z","steps":["trace[1481994488] 'process raft request' (duration: 436.47601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.541588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:46.104699Z","time spent":"436.691826ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:31:46.541567Z","caller":"traceutil/trace.go:171","msg":"trace[1804525125] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"436.771338ms","start":"2026-05-21T19:31:46.104651Z","end":"2026-05-21T19:31:46.541422Z","steps":["trace[1804525125] 'process raft request' (duration: 435.848026ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:46.541873Z","caller":"traceutil/trace.go:171","msg":"trace[320935151] linearizableReadLoop","detail":"{readStateIndex:1664; appliedIndex:1660; }","duration":"138.063788ms","start":"2026-05-21T19:31:46.403781Z","end":"2026-05-21T19:31:46.541845Z","steps":["trace[320935151] 'read index received' (duration: 136.663815ms)","trace[320935151] 'applied index is now lower than readState.Index' (duration: 1.398793ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:46.541952Z","caller":"traceutil/trace.go:171","msg":"trace[1203279623] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"410.960368ms","start":"2026-05-21T19:31:46.130902Z","end":"2026-05-21T19:31:46.541862Z","steps":["trace[1203279623] 'process raft request' (duration: 410.803225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.542065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:46.130878Z","time spent":"411.133983ms","remote":"127.0.0.1:59322","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-05-21T19:31:46.54213Z","caller":"traceutil/trace.go:171","msg":"trace[433727851] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"411.159143ms","start":"2026-05-21T19:31:46.130956Z","end":"2026-05-21T19:31:46.542116Z","steps":["trace[433727851] 'process raft request' (duration: 410.845196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.542082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.285164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-21T19:31:46.542204Z","caller":"traceutil/trace.go:171","msg":"trace[1666134171] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1599; }","duration":"138.459868ms","start":"2026-05-21T19:31:46.403726Z","end":"2026-05-21T19:31:46.542186Z","steps":["trace[1666134171] 'agreement among raft nodes before linearized reading' (duration: 138.227212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:46.542228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:46.130923Z","time spent":"411.243295ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:31:46.543439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:31:46.104637Z","time spent":"437.166447ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:31:48.82623Z","caller":"traceutil/trace.go:171","msg":"trace[1124422904] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"273.367742ms","start":"2026-05-21T19:31:48.552837Z","end":"2026-05-21T19:31:48.826205Z","steps":["trace[1124422904] 'process raft request' (duration: 273.28901ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:48.827103Z","caller":"traceutil/trace.go:171","msg":"trace[985430724] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"275.872931ms","start":"2026-05-21T19:31:48.551187Z","end":"2026-05-21T19:31:48.82706Z","steps":["trace[985430724] 'process raft request' (duration: 274.614691ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:48.827365Z","caller":"traceutil/trace.go:171","msg":"trace[424649290] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"275.566813ms","start":"2026-05-21T19:31:48.551785Z","end":"2026-05-21T19:31:48.827352Z","steps":["trace[424649290] 'process raft request' (duration: 274.295983ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:48.954116Z","caller":"traceutil/trace.go:171","msg":"trace[1057825811] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"119.913589ms","start":"2026-05-21T19:31:48.834167Z","end":"2026-05-21T19:31:48.95408Z","steps":["trace[1057825811] 'process raft request' (duration: 107.196949ms)","trace[1057825811] 'compare' (duration: 12.582977ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:50.342383Z","caller":"traceutil/trace.go:171","msg":"trace[1256918728] linearizableReadLoop","detail":"{readStateIndex:1679; appliedIndex:1678; }","duration":"162.567477ms","start":"2026-05-21T19:31:50.179781Z","end":"2026-05-21T19:31:50.342349Z","steps":["trace[1256918728] 'read index received' (duration: 162.358312ms)","trace[1256918728] 'applied index is now lower than readState.Index' (duration: 208.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:31:50.342509Z","caller":"traceutil/trace.go:171","msg":"trace[601505567] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"187.473855ms","start":"2026-05-21T19:31:50.154997Z","end":"2026-05-21T19:31:50.342471Z","steps":["trace[601505567] 'process raft request' (duration: 187.204998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:50.342564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.759711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:31:50.343198Z","caller":"traceutil/trace.go:171","msg":"trace[1120067140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1613; }","duration":"163.444487ms","start":"2026-05-21T19:31:50.179734Z","end":"2026-05-21T19:31:50.343178Z","steps":["trace[1120067140] 'agreement among raft nodes before linearized reading' (duration: 162.71126ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:39.69093Z","caller":"traceutil/trace.go:171","msg":"trace[220509053] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"112.28444ms","start":"2026-05-21T19:32:39.578618Z","end":"2026-05-21T19:32:39.690902Z","steps":["trace[220509053] 'process raft request' (duration: 112.097405ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:45.093547Z","caller":"traceutil/trace.go:171","msg":"trace[256340015] linearizableReadLoop","detail":"{readStateIndex:2542; appliedIndex:2541; }","duration":"157.08916ms","start":"2026-05-21T19:34:44.936423Z","end":"2026-05-21T19:34:45.093512Z","steps":["trace[256340015] 'read index received' (duration: 156.838814ms)","trace[256340015] 'applied index is now lower than readState.Index' (duration: 248.656µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:45.093854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.398257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-21T19:34:45.093902Z","caller":"traceutil/trace.go:171","msg":"trace[1620127696] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2440; }","duration":"157.51541ms","start":"2026-05-21T19:34:44.936372Z","end":"2026-05-21T19:34:45.093887Z","steps":["trace[1620127696] 'agreement among raft nodes before linearized reading' (duration: 157.279465ms)"],"step_count":1}