{"level":"warn","ts":"2026-08-14T09:07:45.304097Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:07:45.304232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-08-14T09:07:45.304317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:07:45.30435Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-14T09:07:45.304386Z","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-08-14T09:07:45.305322Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-08-14T09:07:45.305468Z","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.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-08-14T09:07:45.376127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"70.384775ms"} {"level":"info","ts":"2026-08-14T09:07:45.613969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-08-14T09:07:45.61406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:07:45.614081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:07:45.614091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:07:45.614097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:07:45.614127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-08-14T09:07:45.637914Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:07:45.665834Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:07:45.726423Z","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-08-14T09:07:45.731651Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:07:45.731937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:07:45.732191Z","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-08-14T09:07:45.73238Z","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-08-14T09:07:45.732503Z","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-08-14T09:07:45.736238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-08-14T09:07:45.736519Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-14T09:07:45.738064Z","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-08-14T09:07:45.738195Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-14T09:07:45.738241Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-14T09:07:45.738494Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:07:45.738554Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:07:46.215201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:07:46.215261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:07:46.215313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-08-14T09:07:46.215336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:07:46.215347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-14T09:07:46.215426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:07:46.21545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-14T09:07:46.239669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:07:46.239774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:07:46.239791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:07:46.239868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:07:46.240815Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:07:46.240933Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:07:46.244912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-08-14T09:07:46.245427Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T09:07:46.262672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:07:46.262827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:07:46.262963Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:07:49.236722Z","caller":"traceutil/trace.go:171","msg":"trace[504167998] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"198.876059ms","start":"2026-08-14T09:07:49.037812Z","end":"2026-08-14T09:07:49.236688Z","steps":["trace[504167998] 'process raft request' (duration: 148.682728ms)","trace[504167998] 'compare' (duration: 49.967186ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:07:49.432937Z","caller":"traceutil/trace.go:171","msg":"trace[1646638766] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"102.705048ms","start":"2026-08-14T09:07:49.330205Z","end":"2026-08-14T09:07:49.43291Z","steps":["trace[1646638766] 'process raft request' (duration: 43.071991ms)","trace[1646638766] 'compare' (duration: 59.498973ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:07:54.962875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.327507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:07:54.963013Z","caller":"traceutil/trace.go:171","msg":"trace[1724479317] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"232.871652ms","start":"2026-08-14T09:07:54.730118Z","end":"2026-08-14T09:07:54.96299Z","steps":["trace[1724479317] 'process raft request' (duration: 25.938891ms)","trace[1724479317] 'compare' (duration: 206.166253ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:00.118324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228480314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:00.619453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228480314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:01.119759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228480314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:01.445452Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.827618223s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:08:01.445924Z","caller":"traceutil/trace.go:171","msg":"trace[603609836] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"1.828110525s","start":"2026-08-14T09:07:59.617791Z","end":"2026-08-14T09:08:01.445902Z","steps":["trace[603609836] 'read index received' (duration: 1.827855098s)","trace[603609836] 'applied index is now lower than readState.Index' (duration: 254.447µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:01.446089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828270348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:08:01.446126Z","caller":"traceutil/trace.go:171","msg":"trace[1402254296] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:361; }","duration":"1.828375871s","start":"2026-08-14T09:07:59.617739Z","end":"2026-08-14T09:08:01.446115Z","steps":["trace[1402254296] 'agreement among raft nodes before linearized reading' (duration: 1.828287729s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:01.446162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:59.617729Z","time spent":"1.828422783s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-08-14T09:08:01.44614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671745883s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:01.44627Z","caller":"traceutil/trace.go:171","msg":"trace[1935079416] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:361; }","duration":"1.671890436s","start":"2026-08-14T09:07:59.774362Z","end":"2026-08-14T09:08:01.446252Z","steps":["trace[1935079416] 'agreement among raft nodes before linearized reading' (duration: 1.671713312s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:01.44627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.668404ms","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-08-14T09:08:01.44634Z","caller":"traceutil/trace.go:171","msg":"trace[1331857686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"735.774826ms","start":"2026-08-14T09:08:00.710549Z","end":"2026-08-14T09:08:01.446324Z","steps":["trace[1331857686] 'agreement among raft nodes before linearized reading' (duration: 735.661023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:01.44637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:00.710526Z","time spent":"735.837468ms","remote":"127.0.0.1:49626","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-08-14T09:08:01.446614Z","caller":"traceutil/trace.go:171","msg":"trace[1561959277] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.8283124s","start":"2026-08-14T09:07:59.617728Z","end":"2026-08-14T09:08:01.446041Z","steps":["trace[1561959277] 'process raft request' (duration: 1.828037763s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:01.446929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:59.617708Z","time spent":"1.828954375s","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:04.063032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.108883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:08:04.063249Z","caller":"traceutil/trace.go:171","msg":"trace[328202500] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"469.81965ms","start":"2026-08-14T09:08:03.593411Z","end":"2026-08-14T09:08:04.063231Z","steps":["trace[328202500] 'read index received' (duration: 359.465091ms)","trace[328202500] 'applied index is now lower than readState.Index' (duration: 110.353099ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:04.063262Z","caller":"traceutil/trace.go:171","msg":"trace[1164228396] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"557.344904ms","start":"2026-08-14T09:08:03.50588Z","end":"2026-08-14T09:08:04.063225Z","steps":["trace[1164228396] 'process raft request' (duration: 446.958584ms)","trace[1164228396] 'compare' (duration: 109.97466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:04.063432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:03.505861Z","time spent":"557.494477ms","remote":"127.0.0.1:49626","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-08-14T09:08:04.06341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.982234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T09:08:04.063509Z","caller":"traceutil/trace.go:171","msg":"trace[64482818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"470.124888ms","start":"2026-08-14T09:08:03.593363Z","end":"2026-08-14T09:08:04.063488Z","steps":["trace[64482818] 'agreement among raft nodes before linearized reading' (duration: 469.936503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:04.063527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.173039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:04.063415Z","caller":"traceutil/trace.go:171","msg":"trace[1101513070] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"556.729839ms","start":"2026-08-14T09:08:03.506554Z","end":"2026-08-14T09:08:04.063283Z","steps":["trace[1101513070] 'process raft request' (duration: 556.574855ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:04.063568Z","caller":"traceutil/trace.go:171","msg":"trace[1227323251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"442.284351ms","start":"2026-08-14T09:08:03.62127Z","end":"2026-08-14T09:08:04.063555Z","steps":["trace[1227323251] 'agreement among raft nodes before linearized reading' (duration: 442.128667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:04.063653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:03.621252Z","time spent":"442.390484ms","remote":"127.0.0.1:49324","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-08-14T09:08:04.063568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:03.593345Z","time spent":"470.20902ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:08:04.063811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:03.506539Z","time spent":"557.088518ms","remote":"127.0.0.1:49626","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-08-14T09:08:04.937398Z","caller":"traceutil/trace.go:171","msg":"trace[742953103] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"313.784909ms","start":"2026-08-14T09:08:04.623574Z","end":"2026-08-14T09:08:04.937359Z","steps":["trace[742953103] 'read index received' (duration: 289.124093ms)","trace[742953103] 'applied index is now lower than readState.Index' (duration: 24.658406ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:04.937761Z","caller":"traceutil/trace.go:171","msg":"trace[1649740064] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"866.617152ms","start":"2026-08-14T09:08:04.070782Z","end":"2026-08-14T09:08:04.937399Z","steps":["trace[1649740064] 'process raft request' (duration: 841.932365ms)","trace[1649740064] 'compare' (duration: 24.402491ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:04.937923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:04.07076Z","time spent":"867.082693ms","remote":"127.0.0.1:49626","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-08-14T09:08:05.029729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.132133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:05.029836Z","caller":"traceutil/trace.go:171","msg":"trace[1648805123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"406.285387ms","start":"2026-08-14T09:08:04.623528Z","end":"2026-08-14T09:08:05.029813Z","steps":["trace[1648805123] 'agreement among raft nodes before linearized reading' (duration: 313.975235ms)","trace[1648805123] 'range keys from in-memory index tree' (duration: 92.158089ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:05.029881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:04.623507Z","time spent":"406.365129ms","remote":"127.0.0.1:49318","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-08-14T09:08:07.056918Z","caller":"traceutil/trace.go:171","msg":"trace[1965595165] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"112.290406ms","start":"2026-08-14T09:08:06.944608Z","end":"2026-08-14T09:08:07.056898Z","steps":["trace[1965595165] 'process raft request' (duration: 112.183854ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:25.278384Z","caller":"traceutil/trace.go:171","msg":"trace[247558611] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"128.608181ms","start":"2026-08-14T09:08:25.149687Z","end":"2026-08-14T09:08:25.278295Z","steps":["trace[247558611] 'process raft request' (duration: 128.409336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.09293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.267525ms","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-08-14T09:08:26.093046Z","caller":"traceutil/trace.go:171","msg":"trace[1074881701] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:485; }","duration":"346.43878ms","start":"2026-08-14T09:08:25.746586Z","end":"2026-08-14T09:08:26.093025Z","steps":["trace[1074881701] 'range keys from in-memory index tree' (duration: 346.184263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.093047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.341871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:08:26.093096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:25.746561Z","time spent":"346.523322ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-08-14T09:08:26.093126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.379152ms","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-08-14T09:08:26.093199Z","caller":"traceutil/trace.go:171","msg":"trace[1835031872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"471.472794ms","start":"2026-08-14T09:08:25.621712Z","end":"2026-08-14T09:08:26.093185Z","steps":["trace[1835031872] 'range keys from in-memory index tree' (duration: 471.117716ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.093139Z","caller":"traceutil/trace.go:171","msg":"trace[1824013787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"471.481274ms","start":"2026-08-14T09:08:25.621633Z","end":"2026-08-14T09:08:26.093114Z","steps":["trace[1824013787] 'range keys from in-memory index tree' (duration: 471.259688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.093255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:25.621703Z","time spent":"471.542546ms","remote":"127.0.0.1:49626","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-08-14T09:08:26.093281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:25.621619Z","time spent":"471.653039ms","remote":"127.0.0.1:49318","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-08-14T09:08:26.409485Z","caller":"traceutil/trace.go:171","msg":"trace[329636884] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"254.880915ms","start":"2026-08-14T09:08:26.154585Z","end":"2026-08-14T09:08:26.409466Z","steps":["trace[329636884] 'read index received' (duration: 254.682351ms)","trace[329636884] 'applied index is now lower than readState.Index' (duration: 197.894µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:26.409544Z","caller":"traceutil/trace.go:171","msg":"trace[823248113] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"311.463274ms","start":"2026-08-14T09:08:26.098059Z","end":"2026-08-14T09:08:26.409523Z","steps":["trace[823248113] 'process raft request' (duration: 311.259829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.409647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:26.098043Z","time spent":"311.534245ms","remote":"127.0.0.1:49626","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-08-14T09:08:26.409646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.041009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:08:26.409685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.833209ms","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-08-14T09:08:26.409763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.010907ms","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-08-14T09:08:26.40984Z","caller":"traceutil/trace.go:171","msg":"trace[52635027] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:486; }","duration":"219.148571ms","start":"2026-08-14T09:08:26.190672Z","end":"2026-08-14T09:08:26.409821Z","steps":["trace[52635027] 'agreement among raft nodes before linearized reading' (duration: 218.979257ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.409789Z","caller":"traceutil/trace.go:171","msg":"trace[2003513026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"229.989443ms","start":"2026-08-14T09:08:26.179786Z","end":"2026-08-14T09:08:26.409775Z","steps":["trace[2003513026] 'agreement among raft nodes before linearized reading' (duration: 229.801208ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.409719Z","caller":"traceutil/trace.go:171","msg":"trace[953394867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"255.151352ms","start":"2026-08-14T09:08:26.154546Z","end":"2026-08-14T09:08:26.409697Z","steps":["trace[953394867] 'agreement among raft nodes before linearized reading' (duration: 254.992418ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:30.655384Z","caller":"traceutil/trace.go:171","msg":"trace[156271461] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"223.317941ms","start":"2026-08-14T09:08:30.432036Z","end":"2026-08-14T09:08:30.655354Z","steps":["trace[156271461] 'read index received' (duration: 222.533282ms)","trace[156271461] 'applied index is now lower than readState.Index' (duration: 783.249µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:30.655442Z","caller":"traceutil/trace.go:171","msg":"trace[1258498605] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"357.000595ms","start":"2026-08-14T09:08:30.29841Z","end":"2026-08-14T09:08:30.655411Z","steps":["trace[1258498605] 'process raft request' (duration: 356.40756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:30.655628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:30.298383Z","time spent":"357.136118ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:30.655656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.538517ms","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-08-14T09:08:30.655755Z","caller":"traceutil/trace.go:171","msg":"trace[682384496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"223.744902ms","start":"2026-08-14T09:08:30.431992Z","end":"2026-08-14T09:08:30.655737Z","steps":["trace[682384496] 'agreement among raft nodes before linearized reading' (duration: 223.540257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:30.655942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.02417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-14T09:08:30.656285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.29686ms","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-08-14T09:08:30.656379Z","caller":"traceutil/trace.go:171","msg":"trace[2137971952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"189.787851ms","start":"2026-08-14T09:08:30.466563Z","end":"2026-08-14T09:08:30.656351Z","steps":["trace[2137971952] 'agreement among raft nodes before linearized reading' (duration: 189.184586ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:30.656036Z","caller":"traceutil/trace.go:171","msg":"trace[1953503332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"186.165564ms","start":"2026-08-14T09:08:30.469852Z","end":"2026-08-14T09:08:30.656018Z","steps":["trace[1953503332] 'agreement among raft nodes before linearized reading' (duration: 185.943058ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:33.33266Z","caller":"traceutil/trace.go:171","msg":"trace[1317169875] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"184.504513ms","start":"2026-08-14T09:08:33.148126Z","end":"2026-08-14T09:08:33.332631Z","steps":["trace[1317169875] 'process raft request' (duration: 183.798136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:34.015377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.414186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:08:34.015538Z","caller":"traceutil/trace.go:171","msg":"trace[573310944] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"678.630544ms","start":"2026-08-14T09:08:33.336885Z","end":"2026-08-14T09:08:34.015516Z","steps":["trace[573310944] 'process raft request' (duration: 351.987143ms)","trace[573310944] 'compare' (duration: 326.270762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:34.015609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:33.336865Z","time spent":"678.709127ms","remote":"127.0.0.1:49626","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-08-14T09:08:34.327389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228480765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:34.800902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.838115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:08:34.801074Z","caller":"traceutil/trace.go:171","msg":"trace[1637103974] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.410378993s","start":"2026-08-14T09:08:33.390649Z","end":"2026-08-14T09:08:34.801028Z","steps":["trace[1637103974] 'process raft request' (duration: 1.231332403s)","trace[1637103974] 'compare' (duration: 178.65212ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:34.801141Z","caller":"traceutil/trace.go:171","msg":"trace[1316508705] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"974.523731ms","start":"2026-08-14T09:08:33.826584Z","end":"2026-08-14T09:08:34.801108Z","steps":["trace[1316508705] 'read index received' (duration: 795.337228ms)","trace[1316508705] 'applied index is now lower than readState.Index' (duration: 179.183183ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:34.801275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:33.390625Z","time spent":"1.410562928s","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:08:34.917172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.125499ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-14T09:08:34.91724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090630889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-thgcv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T09:08:34.917292Z","caller":"traceutil/trace.go:171","msg":"trace[1218853559] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"116.050608ms","start":"2026-08-14T09:08:34.801207Z","end":"2026-08-14T09:08:34.917257Z","steps":["trace[1218853559] 'read index received' (duration: 50.902µs)","trace[1218853559] 'applied index is now lower than readState.Index' (duration: 115.995956ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:34.917323Z","caller":"traceutil/trace.go:171","msg":"trace[1418546021] range","detail":"{range_begin:/registry/pods/kube-system/cilium-thgcv; range_end:; response_count:1; response_revision:598; }","duration":"1.090762363s","start":"2026-08-14T09:08:33.826536Z","end":"2026-08-14T09:08:34.917298Z","steps":["trace[1418546021] 'agreement among raft nodes before linearized reading' (duration: 974.694675ms)","trace[1418546021] 'range keys from in-memory index tree' (duration: 115.856453ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:34.917368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:33.826519Z","time spent":"1.090840574s","remote":"127.0.0.1:49534","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-thgcv\" "} {"level":"warn","ts":"2026-08-14T09:08:34.917451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.288893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-14T09:08:34.917467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.043057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:34.917494Z","caller":"traceutil/trace.go:171","msg":"trace[266170133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"219.348185ms","start":"2026-08-14T09:08:34.698133Z","end":"2026-08-14T09:08:34.917481Z","steps":["trace[266170133] 'agreement among raft nodes before linearized reading' (duration: 219.262813ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:34.917508Z","caller":"traceutil/trace.go:171","msg":"trace[635640977] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:598; }","duration":"104.145919ms","start":"2026-08-14T09:08:34.813346Z","end":"2026-08-14T09:08:34.917491Z","steps":["trace[635640977] 'agreement among raft nodes before linearized reading' (duration: 104.054757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:34.917367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.711988ms","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-08-14T09:08:34.917604Z","caller":"traceutil/trace.go:171","msg":"trace[2138285465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"220.963344ms","start":"2026-08-14T09:08:34.696625Z","end":"2026-08-14T09:08:34.917588Z","steps":["trace[2138285465] 'agreement among raft nodes before linearized reading' (duration: 220.643016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:34.91765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.350813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:34.917732Z","caller":"traceutil/trace.go:171","msg":"trace[1778012693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"295.455026ms","start":"2026-08-14T09:08:34.622257Z","end":"2026-08-14T09:08:34.917712Z","steps":["trace[1778012693] 'agreement among raft nodes before linearized reading' (duration: 295.252451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:34.917698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.036466ms","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-08-14T09:08:34.917938Z","caller":"traceutil/trace.go:171","msg":"trace[1558121443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"221.336613ms","start":"2026-08-14T09:08:34.696542Z","end":"2026-08-14T09:08:34.917878Z","steps":["trace[1558121443] 'agreement among raft nodes before linearized reading' (duration: 220.768729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.123252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228480772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:36.340498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.16591205s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:08:36.340983Z","caller":"traceutil/trace.go:171","msg":"trace[1600041129] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.419092164s","start":"2026-08-14T09:08:34.921864Z","end":"2026-08-14T09:08:36.340956Z","steps":["trace[1600041129] 'process raft request' (duration: 1.418855979s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.341142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:34.92184Z","time spent":"1.419219958s","remote":"127.0.0.1:49626","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-08-14T09:08:36.441796Z","caller":"traceutil/trace.go:171","msg":"trace[1560284674] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.519262658s","start":"2026-08-14T09:08:34.922505Z","end":"2026-08-14T09:08:36.441768Z","steps":["trace[1560284674] 'process raft request' (duration: 1.50570654s)","trace[1560284674] 'compare' (duration: 13.143578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:36.441921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:34.922496Z","time spent":"1.51935902s","remote":"127.0.0.1:49626","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-08-14T09:08:36.441972Z","caller":"traceutil/trace.go:171","msg":"trace[1465902197] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.519303409s","start":"2026-08-14T09:08:34.922656Z","end":"2026-08-14T09:08:36.441959Z","steps":["trace[1465902197] 'process raft request' (duration: 1.518914049s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.442034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:34.922618Z","time spent":"1.519386841s","remote":"127.0.0.1:49626","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-08-14T09:08:36.442165Z","caller":"traceutil/trace.go:171","msg":"trace[250151983] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.508241991s","start":"2026-08-14T09:08:34.933879Z","end":"2026-08-14T09:08:36.442121Z","steps":["trace[250151983] 'process raft request' (duration: 1.507851462s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.442304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:34.933854Z","time spent":"1.508382734s","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:08:36.446361Z","caller":"traceutil/trace.go:171","msg":"trace[578188156] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:623; }","duration":"823.181761ms","start":"2026-08-14T09:08:35.623159Z","end":"2026-08-14T09:08:36.446341Z","steps":["trace[578188156] 'read index received' (duration: 717.594227ms)","trace[578188156] 'applied index is now lower than readState.Index' (duration: 105.586454ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:36.447248Z","caller":"traceutil/trace.go:171","msg":"trace[1072975635] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.136482989s","start":"2026-08-14T09:08:35.310756Z","end":"2026-08-14T09:08:36.447239Z","steps":["trace[1072975635] 'process raft request' (duration: 1.132944754s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.447317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:35.310727Z","time spent":"1.136543261s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:36.44744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.288748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:36.447461Z","caller":"traceutil/trace.go:171","msg":"trace[378782084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"824.3425ms","start":"2026-08-14T09:08:35.623113Z","end":"2026-08-14T09:08:36.447455Z","steps":["trace[378782084] 'agreement among raft nodes before linearized reading' (duration: 824.304189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.447479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:35.623095Z","time spent":"824.38089ms","remote":"127.0.0.1:49318","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-08-14T09:08:36.447595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.058044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T09:08:36.447613Z","caller":"traceutil/trace.go:171","msg":"trace[752390132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"428.113515ms","start":"2026-08-14T09:08:36.019494Z","end":"2026-08-14T09:08:36.447608Z","steps":["trace[752390132] 'agreement among raft nodes before linearized reading' (duration: 428.077494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.447631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:36.019471Z","time spent":"428.154526ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:08:36.967903Z","caller":"traceutil/trace.go:171","msg":"trace[1947769991] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"112.391028ms","start":"2026-08-14T09:08:36.855483Z","end":"2026-08-14T09:08:36.967874Z","steps":["trace[1947769991] 'process raft request' (duration: 109.936979ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:48.734696Z","caller":"traceutil/trace.go:171","msg":"trace[1401838164] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"286.574421ms","start":"2026-08-14T09:08:48.448089Z","end":"2026-08-14T09:08:48.734663Z","steps":["trace[1401838164] 'read index received' (duration: 286.412077ms)","trace[1401838164] 'applied index is now lower than readState.Index' (duration: 161.104µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:48.734799Z","caller":"traceutil/trace.go:171","msg":"trace[2144354374] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"303.731136ms","start":"2026-08-14T09:08:48.431027Z","end":"2026-08-14T09:08:48.734758Z","steps":["trace[2144354374] 'process raft request' (duration: 303.436439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:48.734937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.280136ms","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-08-14T09:08:48.734943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:48.431002Z","time spent":"303.85622ms","remote":"127.0.0.1:49626","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-08-14T09:08:48.734937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.830197ms","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-08-14T09:08:48.734992Z","caller":"traceutil/trace.go:171","msg":"trace[2085237394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"234.344058ms","start":"2026-08-14T09:08:48.500633Z","end":"2026-08-14T09:08:48.734977Z","steps":["trace[2085237394] 'agreement among raft nodes before linearized reading' (duration: 234.200184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:48.735011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.185323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:48.735037Z","caller":"traceutil/trace.go:171","msg":"trace[900930603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"286.93076ms","start":"2026-08-14T09:08:48.448084Z","end":"2026-08-14T09:08:48.735014Z","steps":["trace[900930603] 'agreement among raft nodes before linearized reading' (duration: 286.707064ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:48.735055Z","caller":"traceutil/trace.go:171","msg":"trace[1937250267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"112.250745ms","start":"2026-08-14T09:08:48.62279Z","end":"2026-08-14T09:08:48.73504Z","steps":["trace[1937250267] 'agreement among raft nodes before linearized reading' (duration: 112.195174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:48.7352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.269296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T09:08:48.735261Z","caller":"traceutil/trace.go:171","msg":"trace[1714607652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"198.371018ms","start":"2026-08-14T09:08:48.536875Z","end":"2026-08-14T09:08:48.735246Z","steps":["trace[1714607652] 'agreement among raft nodes before linearized reading' (duration: 198.232285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:48.735356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.69865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-14T09:08:48.735407Z","caller":"traceutil/trace.go:171","msg":"trace[1587162887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"211.788473ms","start":"2026-08-14T09:08:48.523604Z","end":"2026-08-14T09:08:48.735392Z","steps":["trace[1587162887] 'agreement among raft nodes before linearized reading' (duration: 211.613858ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:52.89133Z","caller":"traceutil/trace.go:171","msg":"trace[554722641] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"142.004955ms","start":"2026-08-14T09:08:52.749307Z","end":"2026-08-14T09:08:52.891312Z","steps":["trace[554722641] 'read index received' (duration: 141.822851ms)","trace[554722641] 'applied index is now lower than readState.Index' (duration: 181.244µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:52.891391Z","caller":"traceutil/trace.go:171","msg":"trace[1042467770] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"940.17911ms","start":"2026-08-14T09:08:51.951176Z","end":"2026-08-14T09:08:52.891355Z","steps":["trace[1042467770] 'process raft request' (duration: 940.029986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.891498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.227935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-14T09:08:52.891503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.193529ms","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-08-14T09:08:52.891528Z","caller":"traceutil/trace.go:171","msg":"trace[1305259422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"124.288136ms","start":"2026-08-14T09:08:52.76723Z","end":"2026-08-14T09:08:52.891518Z","steps":["trace[1305259422] 'agreement among raft nodes before linearized reading' (duration: 124.186943ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:52.891556Z","caller":"traceutil/trace.go:171","msg":"trace[1820275242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"142.24591ms","start":"2026-08-14T09:08:52.749292Z","end":"2026-08-14T09:08:52.891538Z","steps":["trace[1820275242] 'agreement among raft nodes before linearized reading' (duration: 142.095537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.891591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:51.951135Z","time spent":"940.357594ms","remote":"127.0.0.1:49590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:52.891633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.999885ms","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-08-14T09:08:52.891705Z","caller":"traceutil/trace.go:171","msg":"trace[2043915429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"118.110727ms","start":"2026-08-14T09:08:52.773578Z","end":"2026-08-14T09:08:52.891688Z","steps":["trace[2043915429] 'agreement among raft nodes before linearized reading' (duration: 117.863711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.891711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.605029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-14T09:08:52.8917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.413514ms","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-08-14T09:08:52.891759Z","caller":"traceutil/trace.go:171","msg":"trace[421761425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"118.682641ms","start":"2026-08-14T09:08:52.773067Z","end":"2026-08-14T09:08:52.89175Z","steps":["trace[421761425] 'agreement among raft nodes before linearized reading' (duration: 118.581448ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:52.891773Z","caller":"traceutil/trace.go:171","msg":"trace[1416270033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"118.510117ms","start":"2026-08-14T09:08:52.773252Z","end":"2026-08-14T09:08:52.891762Z","steps":["trace[1416270033] 'agreement among raft nodes before linearized reading' (duration: 118.351383ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:06.703383Z","caller":"traceutil/trace.go:171","msg":"trace[1357921718] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"238.930989ms","start":"2026-08-14T09:09:06.464427Z","end":"2026-08-14T09:09:06.703358Z","steps":["trace[1357921718] 'read index received' (duration: 238.667433ms)","trace[1357921718] 'applied index is now lower than readState.Index' (duration: 262.456µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:06.703505Z","caller":"traceutil/trace.go:171","msg":"trace[1151066288] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"322.263755ms","start":"2026-08-14T09:09:06.381175Z","end":"2026-08-14T09:09:06.703439Z","steps":["trace[1151066288] 'process raft request' (duration: 321.983968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:06.703591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.141725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:09:06.703641Z","caller":"traceutil/trace.go:171","msg":"trace[96659511] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:896; }","duration":"239.236557ms","start":"2026-08-14T09:09:06.46439Z","end":"2026-08-14T09:09:06.703626Z","steps":["trace[96659511] 'agreement among raft nodes before linearized reading' (duration: 239.141684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:06.703686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:06.38115Z","time spent":"322.43233ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:07.199192Z","caller":"traceutil/trace.go:171","msg":"trace[1844738878] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"491.070117ms","start":"2026-08-14T09:09:06.708106Z","end":"2026-08-14T09:09:07.199176Z","steps":["trace[1844738878] 'process raft request' (duration: 490.937153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.199324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:06.708086Z","time spent":"491.17305ms","remote":"127.0.0.1:49506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:07.214528Z","caller":"traceutil/trace.go:171","msg":"trace[997329713] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"506.053599ms","start":"2026-08-14T09:09:06.708432Z","end":"2026-08-14T09:09:07.214485Z","steps":["trace[997329713] 'process raft request' (duration: 505.810613ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:07.214556Z","caller":"traceutil/trace.go:171","msg":"trace[500363110] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"503.774404ms","start":"2026-08-14T09:09:06.710752Z","end":"2026-08-14T09:09:07.214526Z","steps":["trace[500363110] 'process raft request' (duration: 503.654241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.214699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:06.708423Z","time spent":"506.172193ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:07.214758Z","caller":"traceutil/trace.go:171","msg":"trace[1539368417] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:929; }","duration":"194.996896ms","start":"2026-08-14T09:09:07.019745Z","end":"2026-08-14T09:09:07.214741Z","steps":["trace[1539368417] 'read index received' (duration: 179.407109ms)","trace[1539368417] 'applied index is now lower than readState.Index' (duration: 15.588667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:07.214768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:06.710733Z","time spent":"503.909918ms","remote":"127.0.0.1:49828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:07.214998Z","caller":"traceutil/trace.go:171","msg":"trace[939712353] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"117.261046ms","start":"2026-08-14T09:09:07.097728Z","end":"2026-08-14T09:09:07.214989Z","steps":["trace[939712353] 'process raft request' (duration: 116.735834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.215171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.58843ms","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-08-14T09:09:07.215219Z","caller":"traceutil/trace.go:171","msg":"trace[204512321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"190.658451ms","start":"2026-08-14T09:09:07.024542Z","end":"2026-08-14T09:09:07.215201Z","steps":["trace[204512321] 'agreement among raft nodes before linearized reading' (duration: 190.464277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.215275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.828651ms","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-08-14T09:09:07.215307Z","caller":"traceutil/trace.go:171","msg":"trace[1506442479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"122.934044ms","start":"2026-08-14T09:09:07.092363Z","end":"2026-08-14T09:09:07.215297Z","steps":["trace[1506442479] 'agreement among raft nodes before linearized reading' (duration: 122.806051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.215393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.961078ms","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-08-14T09:09:07.215417Z","caller":"traceutil/trace.go:171","msg":"trace[401843309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"174.003369ms","start":"2026-08-14T09:09:07.041407Z","end":"2026-08-14T09:09:07.21541Z","steps":["trace[401843309] 'agreement among raft nodes before linearized reading' (duration: 173.931077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.215545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.804267ms","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-08-14T09:09:07.215579Z","caller":"traceutil/trace.go:171","msg":"trace[1751551463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"195.882478ms","start":"2026-08-14T09:09:07.019686Z","end":"2026-08-14T09:09:07.215569Z","steps":["trace[1751551463] 'agreement among raft nodes before linearized reading' (duration: 195.763986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.215704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.901881ms","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-08-14T09:09:07.215784Z","caller":"traceutil/trace.go:171","msg":"trace[1768077044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"175.041484ms","start":"2026-08-14T09:09:07.040726Z","end":"2026-08-14T09:09:07.215767Z","steps":["trace[1768077044] 'agreement among raft nodes before linearized reading' (duration: 174.787688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.81325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.815431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:07.813342Z","caller":"traceutil/trace.go:171","msg":"trace[1419580933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"189.954605ms","start":"2026-08-14T09:09:07.623367Z","end":"2026-08-14T09:09:07.813322Z","steps":["trace[1419580933] 'range keys from in-memory index tree' (duration: 189.72418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:11.741713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228481443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:09:12.242203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228481443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:09:12.536229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.308522939s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:09:12.536584Z","caller":"traceutil/trace.go:171","msg":"trace[1512442043] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"1.29585526s","start":"2026-08-14T09:09:11.240706Z","end":"2026-08-14T09:09:12.536562Z","steps":["trace[1512442043] 'read index received' (duration: 1.295628824s)","trace[1512442043] 'applied index is now lower than readState.Index' (duration: 225.656µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:12.536688Z","caller":"traceutil/trace.go:171","msg":"trace[897332356] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.309055363s","start":"2026-08-14T09:09:11.227618Z","end":"2026-08-14T09:09:12.536673Z","steps":["trace[897332356] 'process raft request' (duration: 1.308781986s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.536792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.227594Z","time spent":"1.309127535s","remote":"127.0.0.1:49626","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-08-14T09:09:12.536853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.479947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:09:12.536927Z","caller":"traceutil/trace.go:171","msg":"trace[95304869] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:920; }","duration":"105.59366ms","start":"2026-08-14T09:09:12.431313Z","end":"2026-08-14T09:09:12.536907Z","steps":["trace[95304869] 'agreement among raft nodes before linearized reading' (duration: 105.445827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.536946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296212858s","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-08-14T09:09:12.537012Z","caller":"traceutil/trace.go:171","msg":"trace[1035203174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"1.296320611s","start":"2026-08-14T09:09:11.240677Z","end":"2026-08-14T09:09:12.536997Z","steps":["trace[1035203174] 'agreement among raft nodes before linearized reading' (duration: 1.296128547s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.537049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.240659Z","time spent":"1.296384983s","remote":"127.0.0.1:49626","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-08-14T09:09:12.537037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.023356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:12.537137Z","caller":"traceutil/trace.go:171","msg":"trace[419937432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"915.162699ms","start":"2026-08-14T09:09:11.621951Z","end":"2026-08-14T09:09:12.537114Z","steps":["trace[419937432] 'agreement among raft nodes before linearized reading' (duration: 914.981275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.537215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.621933Z","time spent":"915.273082ms","remote":"127.0.0.1:49318","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-08-14T09:09:12.537252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288687545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-14T09:09:12.537316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288437079s","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-08-14T09:09:12.537354Z","caller":"traceutil/trace.go:171","msg":"trace[573542200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"1.28886968s","start":"2026-08-14T09:09:11.248466Z","end":"2026-08-14T09:09:12.537335Z","steps":["trace[573542200] 'agreement among raft nodes before linearized reading' (duration: 1.288500531s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:12.537371Z","caller":"traceutil/trace.go:171","msg":"trace[1459532527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"1.28851542s","start":"2026-08-14T09:09:11.248841Z","end":"2026-08-14T09:09:12.537356Z","steps":["trace[1459532527] 'agreement among raft nodes before linearized reading' (duration: 1.288314055s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.537417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.24883Z","time spent":"1.288576892s","remote":"127.0.0.1:49626","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-08-14T09:09:12.537425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.248456Z","time spent":"1.288929091s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:09:12.537853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293475312s","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-08-14T09:09:12.537896Z","caller":"traceutil/trace.go:171","msg":"trace[877194337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"1.293547414s","start":"2026-08-14T09:09:11.244333Z","end":"2026-08-14T09:09:12.537881Z","steps":["trace[877194337] 'agreement among raft nodes before linearized reading' (duration: 1.292367405s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.537917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.244312Z","time spent":"1.293599185s","remote":"127.0.0.1:49626","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-08-14T09:09:12.537645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237477113s","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":"info","ts":"2026-08-14T09:09:12.538805Z","caller":"traceutil/trace.go:171","msg":"trace[187373578] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:920; }","duration":"1.23937448s","start":"2026-08-14T09:09:11.299412Z","end":"2026-08-14T09:09:12.538787Z","steps":["trace[187373578] 'agreement among raft nodes before linearized reading' (duration: 1.23732469s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.538868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.299399Z","time spent":"1.239450852s","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:09:12.538375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289837453s","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-08-14T09:09:12.538945Z","caller":"traceutil/trace.go:171","msg":"trace[1977676822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"1.290424998s","start":"2026-08-14T09:09:11.248505Z","end":"2026-08-14T09:09:12.53893Z","steps":["trace[1977676822] 'agreement among raft nodes before linearized reading' (duration: 1.288436209s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.539018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:11.2485Z","time spent":"1.290503729s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:09:12.799115Z","caller":"traceutil/trace.go:171","msg":"trace[1099191329] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"257.04669ms","start":"2026-08-14T09:09:12.542042Z","end":"2026-08-14T09:09:12.799089Z","steps":["trace[1099191329] 'process raft request' (duration: 243.087949ms)","trace[1099191329] 'compare' (duration: 13.838778ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:12.86458Z","caller":"traceutil/trace.go:171","msg":"trace[1123471487] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"321.827962ms","start":"2026-08-14T09:09:12.542735Z","end":"2026-08-14T09:09:12.864563Z","steps":["trace[1123471487] 'process raft request' (duration: 321.696479ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:12.864719Z","caller":"traceutil/trace.go:171","msg":"trace[244100533] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"321.884624ms","start":"2026-08-14T09:09:12.542824Z","end":"2026-08-14T09:09:12.864709Z","steps":["trace[244100533] 'process raft request' (duration: 321.70785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.864741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:12.542712Z","time spent":"321.956646ms","remote":"127.0.0.1:49626","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-08-14T09:09:12.864776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:12.542816Z","time spent":"321.932255ms","remote":"127.0.0.1:49626","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-08-14T09:09:12.865011Z","caller":"traceutil/trace.go:171","msg":"trace[1780084320] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"322.12905ms","start":"2026-08-14T09:09:12.542862Z","end":"2026-08-14T09:09:12.864991Z","steps":["trace[1780084320] 'process raft request' (duration: 321.792052ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:12.865166Z","caller":"traceutil/trace.go:171","msg":"trace[1516968348] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:954; }","duration":"252.708854ms","start":"2026-08-14T09:09:12.612447Z","end":"2026-08-14T09:09:12.865156Z","steps":["trace[1516968348] 'read index received' (duration: 172.707389ms)","trace[1516968348] 'applied index is now lower than readState.Index' (duration: 80.000515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:12.865189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:12.542844Z","time spent":"322.264694ms","remote":"127.0.0.1:49626","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-08-14T09:09:12.865253Z","caller":"traceutil/trace.go:171","msg":"trace[1594969831] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"322.249293ms","start":"2026-08-14T09:09:12.542997Z","end":"2026-08-14T09:09:12.865246Z","steps":["trace[1594969831] 'process raft request' (duration: 321.899504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:12.86531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:12.542984Z","time spent":"322.295734ms","remote":"127.0.0.1:49626","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-08-14T09:09:12.865669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.121475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:09:12.865854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.374861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:12.865963Z","caller":"traceutil/trace.go:171","msg":"trace[393997717] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:925; }","duration":"253.512424ms","start":"2026-08-14T09:09:12.612433Z","end":"2026-08-14T09:09:12.865945Z","steps":["trace[393997717] 'agreement among raft nodes before linearized reading' (duration: 253.252798ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:12.865781Z","caller":"traceutil/trace.go:171","msg":"trace[1892621566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"242.27946ms","start":"2026-08-14T09:09:12.623481Z","end":"2026-08-14T09:09:12.865761Z","steps":["trace[1892621566] 'agreement among raft nodes before linearized reading' (duration: 242.090755ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:41.178949Z","caller":"traceutil/trace.go:171","msg":"trace[1313208973] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"177.126458ms","start":"2026-08-14T09:09:41.001805Z","end":"2026-08-14T09:09:41.178931Z","steps":["trace[1313208973] 'read index received' (duration: 176.923233ms)","trace[1313208973] 'applied index is now lower than readState.Index' (duration: 202.565µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:41.179021Z","caller":"traceutil/trace.go:171","msg":"trace[857597360] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"192.058002ms","start":"2026-08-14T09:09:40.986931Z","end":"2026-08-14T09:09:41.178989Z","steps":["trace[857597360] 'process raft request' (duration: 191.891208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:41.179133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.016719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-14T09:09:41.179163Z","caller":"traceutil/trace.go:171","msg":"trace[583516945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"169.084171ms","start":"2026-08-14T09:09:41.01007Z","end":"2026-08-14T09:09:41.179154Z","steps":["trace[583516945] 'agreement among raft nodes before linearized reading' (duration: 168.972908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:41.17919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.370693ms","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-08-14T09:09:41.179235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.583327ms","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":"warn","ts":"2026-08-14T09:09:41.179272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.993585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:09:41.179306Z","caller":"traceutil/trace.go:171","msg":"trace[1090951107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"168.075447ms","start":"2026-08-14T09:09:41.011221Z","end":"2026-08-14T09:09:41.179297Z","steps":["trace[1090951107] 'agreement among raft nodes before linearized reading' (duration: 167.967864ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:41.179313Z","caller":"traceutil/trace.go:171","msg":"trace[919166035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"163.70301ms","start":"2026-08-14T09:09:41.015595Z","end":"2026-08-14T09:09:41.179298Z","steps":["trace[919166035] 'agreement among raft nodes before linearized reading' (duration: 163.492834ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:41.179251Z","caller":"traceutil/trace.go:171","msg":"trace[759725078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"177.524447ms","start":"2026-08-14T09:09:41.001713Z","end":"2026-08-14T09:09:41.179237Z","steps":["trace[759725078] 'agreement among raft nodes before linearized reading' (duration: 177.296091ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:43.326926Z","caller":"traceutil/trace.go:171","msg":"trace[2050589387] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"138.554825ms","start":"2026-08-14T09:09:43.188334Z","end":"2026-08-14T09:09:43.326888Z","steps":["trace[2050589387] 'process raft request' (duration: 138.398842ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:43.541804Z","caller":"traceutil/trace.go:171","msg":"trace[1697026652] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"209.549529ms","start":"2026-08-14T09:09:43.332234Z","end":"2026-08-14T09:09:43.541783Z","steps":["trace[1697026652] 'process raft request' (duration: 128.558771ms)","trace[1697026652] 'compare' (duration: 80.809594ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:43.573913Z","caller":"traceutil/trace.go:171","msg":"trace[558786373] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"241.329106ms","start":"2026-08-14T09:09:43.332563Z","end":"2026-08-14T09:09:43.573892Z","steps":["trace[558786373] 'process raft request' (duration: 241.151792ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:43.574127Z","caller":"traceutil/trace.go:171","msg":"trace[922832305] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"241.462709ms","start":"2026-08-14T09:09:43.332646Z","end":"2026-08-14T09:09:43.574109Z","steps":["trace[922832305] 'process raft request' (duration: 241.217793ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:43.574478Z","caller":"traceutil/trace.go:171","msg":"trace[427862057] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"241.743196ms","start":"2026-08-14T09:09:43.332724Z","end":"2026-08-14T09:09:43.574467Z","steps":["trace[427862057] 'process raft request' (duration: 241.327596ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:44.7281Z","caller":"traceutil/trace.go:171","msg":"trace[217046641] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"106.866781ms","start":"2026-08-14T09:09:44.621213Z","end":"2026-08-14T09:09:44.72808Z","steps":["trace[217046641] 'read index received' (duration: 106.80569ms)","trace[217046641] 'applied index is now lower than readState.Index' (duration: 59.771µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:44.728177Z","caller":"traceutil/trace.go:171","msg":"trace[643349046] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"338.270354ms","start":"2026-08-14T09:09:44.389866Z","end":"2026-08-14T09:09:44.728137Z","steps":["trace[643349046] 'process raft request' (duration: 338.09074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:44.728252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.023515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:44.728283Z","caller":"traceutil/trace.go:171","msg":"trace[6311891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"107.106187ms","start":"2026-08-14T09:09:44.621168Z","end":"2026-08-14T09:09:44.728274Z","steps":["trace[6311891] 'agreement among raft nodes before linearized reading' (duration: 107.033995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:44.728418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:44.389835Z","time spent":"338.429019ms","remote":"127.0.0.1:49626","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-08-14T09:09:45.149637Z","caller":"traceutil/trace.go:171","msg":"trace[728351688] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"419.766196ms","start":"2026-08-14T09:09:44.72985Z","end":"2026-08-14T09:09:45.149616Z","steps":["trace[728351688] 'read index received' (duration: 383.92426ms)","trace[728351688] 'applied index is now lower than readState.Index' (duration: 35.840736ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:45.149699Z","caller":"traceutil/trace.go:171","msg":"trace[1887428161] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"465.711308ms","start":"2026-08-14T09:09:44.683955Z","end":"2026-08-14T09:09:45.149667Z","steps":["trace[1887428161] 'process raft request' (duration: 429.817301ms)","trace[1887428161] 'compare' (duration: 35.499407ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:45.149777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.913449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:45.149808Z","caller":"traceutil/trace.go:171","msg":"trace[1075815306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"420.003111ms","start":"2026-08-14T09:09:44.729796Z","end":"2026-08-14T09:09:45.149799Z","steps":["trace[1075815306] 'agreement among raft nodes before linearized reading' (duration: 419.901219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:45.149837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:44.729776Z","time spent":"420.054803ms","remote":"127.0.0.1:49324","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-08-14T09:09:45.150314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:44.683935Z","time spent":"465.867092ms","remote":"127.0.0.1:49626","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-08-14T09:10:01.226216Z","caller":"traceutil/trace.go:171","msg":"trace[1028425968] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"220.021625ms","start":"2026-08-14T09:10:01.006178Z","end":"2026-08-14T09:10:01.2262Z","steps":["trace[1028425968] 'process raft request' (duration: 219.920783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:02.642615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228481969,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:10:02.680755Z","caller":"traceutil/trace.go:171","msg":"trace[364352637] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"539.896951ms","start":"2026-08-14T09:10:02.140829Z","end":"2026-08-14T09:10:02.680726Z","steps":["trace[364352637] 'process raft request' (duration: 539.708517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:02.681017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.140791Z","time spent":"540.134526ms","remote":"127.0.0.1:49626","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-08-14T09:10:03.302953Z","caller":"traceutil/trace.go:171","msg":"trace[1841062664] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1181; }","duration":"1.161307913s","start":"2026-08-14T09:10:02.141624Z","end":"2026-08-14T09:10:03.302932Z","steps":["trace[1841062664] 'read index received' (duration: 538.891336ms)","trace[1841062664] 'applied index is now lower than readState.Index' (duration: 622.415437ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:03.302998Z","caller":"traceutil/trace.go:171","msg":"trace[73772553] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"1.162042421s","start":"2026-08-14T09:10:02.140908Z","end":"2026-08-14T09:10:03.302951Z","steps":["trace[73772553] 'process raft request' (duration: 1.158757681s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:03.303108Z","caller":"traceutil/trace.go:171","msg":"trace[1348343809] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"1.160942444s","start":"2026-08-14T09:10:02.142149Z","end":"2026-08-14T09:10:03.303091Z","steps":["trace[1348343809] 'process raft request' (duration: 1.160695378s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.303115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.140894Z","time spent":"1.162161214s","remote":"127.0.0.1:49626","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-08-14T09:10:03.303366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.142139Z","time spent":"1.161009756s","remote":"127.0.0.1:49626","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-08-14T09:10:03.303704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162080042s","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-08-14T09:10:03.30374Z","caller":"traceutil/trace.go:171","msg":"trace[906997012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1139; }","duration":"1.162143373s","start":"2026-08-14T09:10:02.141585Z","end":"2026-08-14T09:10:03.303728Z","steps":["trace[906997012] 'agreement among raft nodes before linearized reading' (duration: 1.16201513s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.303774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.141561Z","time spent":"1.162206295s","remote":"127.0.0.1:49626","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-08-14T09:10:03.303928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.233434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:03.30396Z","caller":"traceutil/trace.go:171","msg":"trace[1394373948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"681.296886ms","start":"2026-08-14T09:10:02.622652Z","end":"2026-08-14T09:10:03.303949Z","steps":["trace[1394373948] 'agreement among raft nodes before linearized reading' (duration: 681.253305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.303992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.622631Z","time spent":"681.352337ms","remote":"127.0.0.1:49318","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-08-14T09:10:03.30432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.903058ms","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-08-14T09:10:03.304359Z","caller":"traceutil/trace.go:171","msg":"trace[989938662] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1139; }","duration":"306.975361ms","start":"2026-08-14T09:10:02.997373Z","end":"2026-08-14T09:10:03.304348Z","steps":["trace[989938662] 'agreement among raft nodes before linearized reading' (duration: 306.904609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.304396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:02.997359Z","time spent":"307.025141ms","remote":"127.0.0.1:36898","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":"warn","ts":"2026-08-14T09:10:05.755324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.9738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:05.75538Z","caller":"traceutil/trace.go:171","msg":"trace[933373367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"133.076661ms","start":"2026-08-14T09:10:05.62229Z","end":"2026-08-14T09:10:05.755367Z","steps":["trace[933373367] 'range keys from in-memory index tree' (duration: 132.921918ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:19.37226Z","caller":"traceutil/trace.go:171","msg":"trace[175020558] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"136.227696ms","start":"2026-08-14T09:10:19.236009Z","end":"2026-08-14T09:10:19.372236Z","steps":["trace[175020558] 'process raft request' (duration: 136.089883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:20.595235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.161679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:10:20.595303Z","caller":"traceutil/trace.go:171","msg":"trace[1986830853] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1214; }","duration":"137.301812ms","start":"2026-08-14T09:10:20.457988Z","end":"2026-08-14T09:10:20.59529Z","steps":["trace[1986830853] 'count revisions from in-memory index tree' (duration: 137.069097ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:21.901927Z","caller":"traceutil/trace.go:171","msg":"trace[1512292357] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"165.208739ms","start":"2026-08-14T09:10:21.736696Z","end":"2026-08-14T09:10:21.901905Z","steps":["trace[1512292357] 'read index received' (duration: 138.85682ms)","trace[1512292357] 'applied index is now lower than readState.Index' (duration: 26.350879ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:21.902044Z","caller":"traceutil/trace.go:171","msg":"trace[243502324] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"228.014084ms","start":"2026-08-14T09:10:21.674018Z","end":"2026-08-14T09:10:21.902032Z","steps":["trace[243502324] 'process raft request' (duration: 201.640083ms)","trace[243502324] 'compare' (duration: 26.146495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:21.902178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.476766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:21.902218Z","caller":"traceutil/trace.go:171","msg":"trace[2084353556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1221; }","duration":"165.589109ms","start":"2026-08-14T09:10:21.736617Z","end":"2026-08-14T09:10:21.902206Z","steps":["trace[2084353556] 'agreement among raft nodes before linearized reading' (duration: 165.516227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:23.445113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.079083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:23.445242Z","caller":"traceutil/trace.go:171","msg":"trace[175657598] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"571.204373ms","start":"2026-08-14T09:10:22.874019Z","end":"2026-08-14T09:10:23.445223Z","steps":["trace[175657598] 'read index received' (duration: 85.881164ms)","trace[175657598] 'applied index is now lower than readState.Index' (duration: 485.322029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:23.445452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.426668ms","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-08-14T09:10:23.445474Z","caller":"traceutil/trace.go:171","msg":"trace[1137545671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"571.48906ms","start":"2026-08-14T09:10:22.873976Z","end":"2026-08-14T09:10:23.445466Z","steps":["trace[1137545671] 'agreement among raft nodes before linearized reading' (duration: 571.289025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:23.445501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:22.873961Z","time spent":"571.53454ms","remote":"127.0.0.1:49626","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-08-14T09:10:23.445638Z","caller":"traceutil/trace.go:171","msg":"trace[1466889645] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"953.124872ms","start":"2026-08-14T09:10:22.492467Z","end":"2026-08-14T09:10:23.445592Z","steps":["trace[1466889645] 'process raft request' (duration: 467.478756ms)","trace[1466889645] 'compare' (duration: 484.920798ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:23.446109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:22.492448Z","time spent":"953.275085ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:10:24.354464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.122474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:24.354754Z","caller":"traceutil/trace.go:171","msg":"trace[1158892924] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1274; }","duration":"898.165927ms","start":"2026-08-14T09:10:23.456566Z","end":"2026-08-14T09:10:24.354732Z","steps":["trace[1158892924] 'read index received' (duration: 387.127555ms)","trace[1158892924] 'applied index is now lower than readState.Index' (duration: 511.037292ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:24.354875Z","caller":"traceutil/trace.go:171","msg":"trace[515960218] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.411077095s","start":"2026-08-14T09:10:22.943772Z","end":"2026-08-14T09:10:24.354849Z","steps":["trace[515960218] 'process raft request' (duration: 899.4973ms)","trace[515960218] 'compare' (duration: 510.96882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:24.354947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:22.943749Z","time spent":"1.411161507s","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:10:24.355403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.816873ms","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-08-14T09:10:24.355464Z","caller":"traceutil/trace.go:171","msg":"trace[1327158509] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"926.29027ms","start":"2026-08-14T09:10:23.429166Z","end":"2026-08-14T09:10:24.355456Z","steps":["trace[1327158509] 'process raft request' (duration: 925.414539ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:24.355473Z","caller":"traceutil/trace.go:171","msg":"trace[1033993839] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1228; }","duration":"898.935886ms","start":"2026-08-14T09:10:23.456523Z","end":"2026-08-14T09:10:24.355459Z","steps":["trace[1033993839] 'agreement among raft nodes before linearized reading' (duration: 898.723371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.901326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-14T09:10:24.355463Z","caller":"traceutil/trace.go:171","msg":"trace[904086914] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"898.786023ms","start":"2026-08-14T09:10:23.45665Z","end":"2026-08-14T09:10:24.355436Z","steps":["trace[904086914] 'process raft request' (duration: 898.034954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.456504Z","time spent":"899.002967ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-14T09:10:24.355531Z","caller":"traceutil/trace.go:171","msg":"trace[1547361015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"863.985409ms","start":"2026-08-14T09:10:23.491532Z","end":"2026-08-14T09:10:24.355517Z","steps":["trace[1547361015] 'agreement among raft nodes before linearized reading' (duration: 863.830955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.491511Z","time spent":"864.04868ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:24.355575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.42915Z","time spent":"926.350101ms","remote":"127.0.0.1:49626","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-08-14T09:10:24.355408Z","caller":"traceutil/trace.go:171","msg":"trace[1329995212] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"899.250624ms","start":"2026-08-14T09:10:23.456131Z","end":"2026-08-14T09:10:24.355381Z","steps":["trace[1329995212] 'process raft request' (duration: 898.519586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.456639Z","time spent":"898.893165ms","remote":"127.0.0.1:49626","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-08-14T09:10:24.355646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.456085Z","time spent":"899.538801ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:10:24.355696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.376787ms","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-08-14T09:10:24.355725Z","caller":"traceutil/trace.go:171","msg":"trace[1027511935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"681.436948ms","start":"2026-08-14T09:10:23.674279Z","end":"2026-08-14T09:10:24.355716Z","steps":["trace[1027511935] 'agreement among raft nodes before linearized reading' (duration: 681.354316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.441985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:10:24.355751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.67426Z","time spent":"681.485399ms","remote":"127.0.0.1:49626","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-08-14T09:10:24.355765Z","caller":"traceutil/trace.go:171","msg":"trace[977466631] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1228; }","duration":"802.514146ms","start":"2026-08-14T09:10:23.553242Z","end":"2026-08-14T09:10:24.355756Z","steps":["trace[977466631] 'agreement among raft nodes before linearized reading' (duration: 802.477635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.941472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:10:24.355787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.553202Z","time spent":"802.579868ms","remote":"127.0.0.1:36948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:24.355821Z","caller":"traceutil/trace.go:171","msg":"trace[809093029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"734.015704ms","start":"2026-08-14T09:10:23.621789Z","end":"2026-08-14T09:10:24.355805Z","steps":["trace[809093029] 'agreement among raft nodes before linearized reading' (duration: 733.954552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.621728Z","time spent":"734.121607ms","remote":"127.0.0.1:49318","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-08-14T09:10:24.355834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.482488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-14T09:10:24.355912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.229464ms","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-08-14T09:10:24.355931Z","caller":"traceutil/trace.go:171","msg":"trace[1552307632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"449.633522ms","start":"2026-08-14T09:10:23.906273Z","end":"2026-08-14T09:10:24.355906Z","steps":["trace[1552307632] 'agreement among raft nodes before linearized reading' (duration: 449.358665ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:24.355945Z","caller":"traceutil/trace.go:171","msg":"trace[625451822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"864.312586ms","start":"2026-08-14T09:10:23.491623Z","end":"2026-08-14T09:10:24.355935Z","steps":["trace[625451822] 'agreement among raft nodes before linearized reading' (duration: 864.224514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.355953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.562819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:10:24.355976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.491598Z","time spent":"864.370308ms","remote":"127.0.0.1:49626","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-08-14T09:10:24.355979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:23.906248Z","time spent":"449.720484ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:10:24.355993Z","caller":"traceutil/trace.go:171","msg":"trace[817991898] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1228; }","duration":"137.648921ms","start":"2026-08-14T09:10:24.218332Z","end":"2026-08-14T09:10:24.355981Z","steps":["trace[817991898] 'agreement among raft nodes before linearized reading' (duration: 137.585649ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:25.118031Z","caller":"traceutil/trace.go:171","msg":"trace[853984755] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"757.201546ms","start":"2026-08-14T09:10:24.360799Z","end":"2026-08-14T09:10:25.118Z","steps":["trace[853984755] 'process raft request' (duration: 756.705664ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:25.118048Z","caller":"traceutil/trace.go:171","msg":"trace[1031327835] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"756.95843ms","start":"2026-08-14T09:10:24.361053Z","end":"2026-08-14T09:10:25.118011Z","steps":["trace[1031327835] 'process raft request' (duration: 756.878178ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:25.118111Z","caller":"traceutil/trace.go:171","msg":"trace[616533503] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"757.127215ms","start":"2026-08-14T09:10:24.360937Z","end":"2026-08-14T09:10:25.118064Z","steps":["trace[616533503] 'process raft request' (duration: 756.912709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:25.118156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.36079Z","time spent":"757.306439ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:10:25.118157Z","caller":"traceutil/trace.go:171","msg":"trace[954263094] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"757.152496ms","start":"2026-08-14T09:10:24.360958Z","end":"2026-08-14T09:10:25.11811Z","steps":["trace[954263094] 'process raft request' (duration: 756.948641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:25.118248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.361045Z","time spent":"757.099494ms","remote":"127.0.0.1:49626","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-08-14T09:10:25.11828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.36092Z","time spent":"757.274698ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:25.118318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.360946Z","time spent":"757.288259ms","remote":"127.0.0.1:49626","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-08-14T09:10:25.118305Z","caller":"traceutil/trace.go:171","msg":"trace[1734164832] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"757.152706ms","start":"2026-08-14T09:10:24.361118Z","end":"2026-08-14T09:10:25.118271Z","steps":["trace[1734164832] 'process raft request' (duration: 756.838278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:25.118485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.361111Z","time spent":"757.275998ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:26.218071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.582026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-14T09:10:26.219289Z","caller":"traceutil/trace.go:171","msg":"trace[1110386158] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.247027703s","start":"2026-08-14T09:10:24.972228Z","end":"2026-08-14T09:10:26.219255Z","steps":["trace[1110386158] 'process raft request' (duration: 1.245945007s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.219472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.97221Z","time spent":"1.247160416s","remote":"127.0.0.1:49626","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-08-14T09:10:26.940776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.438799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:26.941058Z","caller":"traceutil/trace.go:171","msg":"trace[1852076367] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"578.94757ms","start":"2026-08-14T09:10:26.362086Z","end":"2026-08-14T09:10:26.941033Z","steps":["trace[1852076367] 'process raft request' (duration: 578.823897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.941147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:26.362051Z","time spent":"579.057093ms","remote":"127.0.0.1:49626","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-08-14T09:10:26.94124Z","caller":"traceutil/trace.go:171","msg":"trace[1086326122] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1279; }","duration":"2.053124966s","start":"2026-08-14T09:10:24.888086Z","end":"2026-08-14T09:10:26.941211Z","steps":["trace[1086326122] 'read index received' (duration: 229.625003ms)","trace[1086326122] 'applied index is now lower than readState.Index' (duration: 1.823494053s)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:26.941373Z","caller":"traceutil/trace.go:171","msg":"trace[201682215] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"1.89795411s","start":"2026-08-14T09:10:25.043409Z","end":"2026-08-14T09:10:26.941363Z","steps":["trace[201682215] 'process raft request' (duration: 1.175817215s)","trace[201682215] 'compare' (duration: 721.293874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:26.941482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:25.043381Z","time spent":"1.898012392s","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5964,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:26.941545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974649402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:26.941601Z","caller":"traceutil/trace.go:171","msg":"trace[1348137089] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1237; }","duration":"1.974760594s","start":"2026-08-14T09:10:24.966823Z","end":"2026-08-14T09:10:26.941584Z","steps":["trace[1348137089] 'agreement among raft nodes before linearized reading' (duration: 1.974636801s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.941622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.053501625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:10:26.941641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.966805Z","time spent":"1.974819385s","remote":"127.0.0.1:36942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:26.941674Z","caller":"traceutil/trace.go:171","msg":"trace[937896407] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1237; }","duration":"2.053606597s","start":"2026-08-14T09:10:24.888052Z","end":"2026-08-14T09:10:26.941659Z","steps":["trace[937896407] 'agreement among raft nodes before linearized reading' (duration: 2.053490864s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.94172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:24.888034Z","time spent":"2.053673309s","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:10:26.941539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.444659ms","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-08-14T09:10:26.94173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.724499ms","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-08-14T09:10:26.941854Z","caller":"traceutil/trace.go:171","msg":"trace[2116318207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"579.899923ms","start":"2026-08-14T09:10:26.361932Z","end":"2026-08-14T09:10:26.941832Z","steps":["trace[2116318207] 'agreement among raft nodes before linearized reading' (duration: 579.556445ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:26.941815Z","caller":"traceutil/trace.go:171","msg":"trace[1970008992] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1237; }","duration":"208.758677ms","start":"2026-08-14T09:10:26.733038Z","end":"2026-08-14T09:10:26.941797Z","steps":["trace[1970008992] 'agreement among raft nodes before linearized reading' (duration: 208.445839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.941906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:26.361916Z","time spent":"579.980495ms","remote":"127.0.0.1:49626","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-08-14T09:10:26.941946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.640072ms","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-08-14T09:10:26.941977Z","caller":"traceutil/trace.go:171","msg":"trace[1281628119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"484.707334ms","start":"2026-08-14T09:10:26.457262Z","end":"2026-08-14T09:10:26.941969Z","steps":["trace[1281628119] 'agreement among raft nodes before linearized reading' (duration: 484.55703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.941996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:26.45724Z","time spent":"484.751694ms","remote":"127.0.0.1:49626","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-08-14T09:10:26.941808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318689042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:26.942052Z","caller":"traceutil/trace.go:171","msg":"trace[1147916052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"1.318959068s","start":"2026-08-14T09:10:25.623081Z","end":"2026-08-14T09:10:26.94204Z","steps":["trace[1147916052] 'agreement among raft nodes before linearized reading' (duration: 1.318704572s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:26.942092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:25.623062Z","time spent":"1.31901901s","remote":"127.0.0.1:49318","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-08-14T09:10:27.622978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228482182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:10:27.639567Z","caller":"traceutil/trace.go:171","msg":"trace[42982965] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"517.191202ms","start":"2026-08-14T09:10:27.12235Z","end":"2026-08-14T09:10:27.639541Z","steps":["trace[42982965] 'read index received' (duration: 517.022258ms)","trace[42982965] 'applied index is now lower than readState.Index' (duration: 167.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:27.63981Z","caller":"traceutil/trace.go:171","msg":"trace[718929194] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"693.465939ms","start":"2026-08-14T09:10:26.946325Z","end":"2026-08-14T09:10:27.639791Z","steps":["trace[718929194] 'process raft request' (duration: 693.044179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:27.639909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.743739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:10:27.639958Z","caller":"traceutil/trace.go:171","msg":"trace[1414087107] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1239; }","duration":"464.839941ms","start":"2026-08-14T09:10:27.175106Z","end":"2026-08-14T09:10:27.639946Z","steps":["trace[1414087107] 'agreement among raft nodes before linearized reading' (duration: 464.733758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:27.639946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:26.946314Z","time spent":"693.560052ms","remote":"127.0.0.1:49626","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-08-14T09:10:27.639998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.17509Z","time spent":"464.898473ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:10:27.640024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.568161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-14T09:10:27.640123Z","caller":"traceutil/trace.go:171","msg":"trace[892353686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"517.700595ms","start":"2026-08-14T09:10:27.122399Z","end":"2026-08-14T09:10:27.6401Z","steps":["trace[892353686] 'agreement among raft nodes before linearized reading' (duration: 517.437758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:27.640183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.122386Z","time spent":"517.785266ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:27.640354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.681514ms","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-08-14T09:10:27.640378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.021952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T09:10:27.640387Z","caller":"traceutil/trace.go:171","msg":"trace[829509091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"517.726864ms","start":"2026-08-14T09:10:27.12265Z","end":"2026-08-14T09:10:27.640377Z","steps":["trace[829509091] 'agreement among raft nodes before linearized reading' (duration: 517.640812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:27.640421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.122644Z","time spent":"517.771456ms","remote":"127.0.0.1:49626","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-08-14T09:10:27.64042Z","caller":"traceutil/trace.go:171","msg":"trace[1308830525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"518.091694ms","start":"2026-08-14T09:10:27.122319Z","end":"2026-08-14T09:10:27.64041Z","steps":["trace[1308830525] 'agreement among raft nodes before linearized reading' (duration: 517.367067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:27.64046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.122301Z","time spent":"518.150795ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:28.059448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.065135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:28.059534Z","caller":"traceutil/trace.go:171","msg":"trace[1809324556] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"410.901502ms","start":"2026-08-14T09:10:27.648616Z","end":"2026-08-14T09:10:28.059518Z","steps":["trace[1809324556] 'process raft request' (duration: 182.710454ms)","trace[1809324556] 'compare' (duration: 227.984113ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:28.059589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.648595Z","time spent":"410.967574ms","remote":"127.0.0.1:49626","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-08-14T09:10:28.108949Z","caller":"traceutil/trace.go:171","msg":"trace[609812125] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"458.447956ms","start":"2026-08-14T09:10:27.650483Z","end":"2026-08-14T09:10:28.108931Z","steps":["trace[609812125] 'process raft request' (duration: 458.407425ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:28.109027Z","caller":"traceutil/trace.go:171","msg":"trace[438455478] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"458.551408ms","start":"2026-08-14T09:10:27.650439Z","end":"2026-08-14T09:10:28.10899Z","steps":["trace[438455478] 'process raft request' (duration: 458.354413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:28.109072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.650469Z","time spent":"458.536958ms","remote":"127.0.0.1:49626","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-08-14T09:10:28.109201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:27.650431Z","time spent":"458.677142ms","remote":"127.0.0.1:49626","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-08-14T09:10:29.495953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228482191,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:10:29.567886Z","caller":"traceutil/trace.go:171","msg":"trace[1187863488] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"572.648666ms","start":"2026-08-14T09:10:28.995212Z","end":"2026-08-14T09:10:29.56786Z","steps":["trace[1187863488] 'read index received' (duration: 572.41726ms)","trace[1187863488] 'applied index is now lower than readState.Index' (duration: 229.986µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:29.567937Z","caller":"traceutil/trace.go:171","msg":"trace[1803217595] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"620.145409ms","start":"2026-08-14T09:10:28.947761Z","end":"2026-08-14T09:10:29.567906Z","steps":["trace[1803217595] 'process raft request' (duration: 619.876013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:29.568325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.912023ms","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-08-14T09:10:29.568396Z","caller":"traceutil/trace.go:171","msg":"trace[74719492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"573.217081ms","start":"2026-08-14T09:10:28.995159Z","end":"2026-08-14T09:10:29.568376Z","steps":["trace[74719492] 'agreement among raft nodes before linearized reading' (duration: 572.823911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:29.568333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:28.947738Z","time spent":"620.261532ms","remote":"127.0.0.1:49626","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-08-14T09:10:29.56859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:28.99514Z","time spent":"573.370654ms","remote":"127.0.0.1:49626","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-08-14T09:10:35.30814Z","caller":"traceutil/trace.go:171","msg":"trace[1072768012] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"175.671294ms","start":"2026-08-14T09:10:35.132443Z","end":"2026-08-14T09:10:35.308114Z","steps":["trace[1072768012] 'read index received' (duration: 175.51184ms)","trace[1072768012] 'applied index is now lower than readState.Index' (duration: 158.374µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:35.308295Z","caller":"traceutil/trace.go:171","msg":"trace[1177570343] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"211.636986ms","start":"2026-08-14T09:10:35.09663Z","end":"2026-08-14T09:10:35.308267Z","steps":["trace[1177570343] 'process raft request' (duration: 211.337119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:35.308549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.092364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-08-14T09:10:35.308659Z","caller":"traceutil/trace.go:171","msg":"trace[917895881] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1280; }","duration":"176.166236ms","start":"2026-08-14T09:10:35.13241Z","end":"2026-08-14T09:10:35.308576Z","steps":["trace[917895881] 'agreement among raft nodes before linearized reading' (duration: 175.850838ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:35.426133Z","caller":"traceutil/trace.go:171","msg":"trace[1982727724] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"103.506922ms","start":"2026-08-14T09:10:35.322594Z","end":"2026-08-14T09:10:35.426101Z","steps":["trace[1982727724] 'process raft request' (duration: 103.342018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:36.410001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.836433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:36.410092Z","caller":"traceutil/trace.go:171","msg":"trace[1669924669] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"150.100213ms","start":"2026-08-14T09:10:36.259975Z","end":"2026-08-14T09:10:36.410076Z","steps":["trace[1669924669] 'process raft request' (duration: 43.101036ms)","trace[1669924669] 'compare' (duration: 106.73168ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:36.450869Z","caller":"traceutil/trace.go:171","msg":"trace[1797991044] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"113.585076ms","start":"2026-08-14T09:10:36.337257Z","end":"2026-08-14T09:10:36.450842Z","steps":["trace[1797991044] 'read index received' (duration: 113.289549ms)","trace[1797991044] 'applied index is now lower than readState.Index' (duration: 294.107µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:36.450901Z","caller":"traceutil/trace.go:171","msg":"trace[303647043] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"190.627075ms","start":"2026-08-14T09:10:36.260243Z","end":"2026-08-14T09:10:36.450871Z","steps":["trace[303647043] 'process raft request' (duration: 190.395659ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:36.450914Z","caller":"traceutil/trace.go:171","msg":"trace[1567424460] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"189.119098ms","start":"2026-08-14T09:10:36.261773Z","end":"2026-08-14T09:10:36.450892Z","steps":["trace[1567424460] 'process raft request' (duration: 189.013536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:36.451103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.821042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5d44r\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-08-14T09:10:36.451165Z","caller":"traceutil/trace.go:171","msg":"trace[1584796717] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5d44r; range_end:; response_count:1; response_revision:1299; }","duration":"113.918954ms","start":"2026-08-14T09:10:36.337226Z","end":"2026-08-14T09:10:36.451145Z","steps":["trace[1584796717] 'agreement among raft nodes before linearized reading' (duration: 113.721719ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:37.717261Z","caller":"traceutil/trace.go:171","msg":"trace[1413751024] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"121.692354ms","start":"2026-08-14T09:10:37.595525Z","end":"2026-08-14T09:10:37.717217Z","steps":["trace[1413751024] 'process raft request' (duration: 121.385096ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:37.84391Z","caller":"traceutil/trace.go:171","msg":"trace[1995038914] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"119.88499ms","start":"2026-08-14T09:10:37.723992Z","end":"2026-08-14T09:10:37.843878Z","steps":["trace[1995038914] 'process raft request' (duration: 51.218153ms)","trace[1995038914] 'compare' (duration: 68.488742ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:40.476448Z","caller":"traceutil/trace.go:171","msg":"trace[1937674896] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"103.992394ms","start":"2026-08-14T09:10:40.372434Z","end":"2026-08-14T09:10:40.476426Z","steps":["trace[1937674896] 'process raft request' (duration: 100.12471ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:40.626396Z","caller":"traceutil/trace.go:171","msg":"trace[1506665412] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"144.848625ms","start":"2026-08-14T09:10:40.48152Z","end":"2026-08-14T09:10:40.626369Z","steps":["trace[1506665412] 'process raft request' (duration: 144.613949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:46.288534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.041182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:46.288755Z","caller":"traceutil/trace.go:171","msg":"trace[882987605] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"281.932582ms","start":"2026-08-14T09:10:46.006795Z","end":"2026-08-14T09:10:46.288728Z","steps":["trace[882987605] 'process raft request' (duration: 159.604423ms)","trace[882987605] 'compare' (duration: 121.756475ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:46.288781Z","caller":"traceutil/trace.go:171","msg":"trace[1490723683] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"281.283006ms","start":"2026-08-14T09:10:46.007477Z","end":"2026-08-14T09:10:46.28876Z","steps":["trace[1490723683] 'process raft request' (duration: 281.170383ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:50.784601Z","caller":"traceutil/trace.go:171","msg":"trace[1675399167] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"109.418055ms","start":"2026-08-14T09:10:50.675161Z","end":"2026-08-14T09:10:50.784579Z","steps":["trace[1675399167] 'read index received' (duration: 109.20955ms)","trace[1675399167] 'applied index is now lower than readState.Index' (duration: 207.255µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:50.784658Z","caller":"traceutil/trace.go:171","msg":"trace[2085585513] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"172.69542ms","start":"2026-08-14T09:10:50.611923Z","end":"2026-08-14T09:10:50.784618Z","steps":["trace[2085585513] 'process raft request' (duration: 172.527526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:50.784978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.789904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-14T09:10:50.785116Z","caller":"traceutil/trace.go:171","msg":"trace[575301942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"109.963947ms","start":"2026-08-14T09:10:50.675132Z","end":"2026-08-14T09:10:50.785096Z","steps":["trace[575301942] 'agreement among raft nodes before linearized reading' (duration: 109.66665ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:56.16487Z","caller":"traceutil/trace.go:171","msg":"trace[2133363787] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"106.216388ms","start":"2026-08-14T09:10:56.058634Z","end":"2026-08-14T09:10:56.16485Z","steps":["trace[2133363787] 'read index received' (duration: 106.046544ms)","trace[2133363787] 'applied index is now lower than readState.Index' (duration: 169.184µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:56.164926Z","caller":"traceutil/trace.go:171","msg":"trace[1978747581] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"299.517009ms","start":"2026-08-14T09:10:55.865377Z","end":"2026-08-14T09:10:56.164894Z","steps":["trace[1978747581] 'process raft request' (duration: 299.353395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:56.165108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.449404ms","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:531"} {"level":"info","ts":"2026-08-14T09:10:56.16518Z","caller":"traceutil/trace.go:171","msg":"trace[206081199] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"106.570317ms","start":"2026-08-14T09:10:56.05859Z","end":"2026-08-14T09:10:56.16516Z","steps":["trace[206081199] 'agreement among raft nodes before linearized reading' (duration: 106.341191ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:58.271422Z","caller":"traceutil/trace.go:171","msg":"trace[481611195] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"135.051358ms","start":"2026-08-14T09:10:58.136342Z","end":"2026-08-14T09:10:58.271393Z","steps":["trace[481611195] 'process raft request' (duration: 134.762091ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:58.288933Z","caller":"traceutil/trace.go:171","msg":"trace[1277699867] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1455; }","duration":"107.784005ms","start":"2026-08-14T09:10:58.181118Z","end":"2026-08-14T09:10:58.288902Z","steps":["trace[1277699867] 'read index received' (duration: 89.912312ms)","trace[1277699867] 'applied index is now lower than readState.Index' (duration: 17.870693ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:58.289166Z","caller":"traceutil/trace.go:171","msg":"trace[1217947786] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"118.530196ms","start":"2026-08-14T09:10:58.170614Z","end":"2026-08-14T09:10:58.289144Z","steps":["trace[1217947786] 'process raft request' (duration: 118.117426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:58.289403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.272068ms","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:531"} {"level":"info","ts":"2026-08-14T09:10:58.289566Z","caller":"traceutil/trace.go:171","msg":"trace[1765516635] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1400; }","duration":"108.404711ms","start":"2026-08-14T09:10:58.181063Z","end":"2026-08-14T09:10:58.289468Z","steps":["trace[1765516635] 'agreement among raft nodes before linearized reading' (duration: 107.94263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:58.616032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.070541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T09:10:58.616091Z","caller":"traceutil/trace.go:171","msg":"trace[2040108505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1401; }","duration":"131.176074ms","start":"2026-08-14T09:10:58.484902Z","end":"2026-08-14T09:10:58.616078Z","steps":["trace[2040108505] 'range keys from in-memory index tree' (duration: 130.741943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:58.616032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.840682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-14T09:10:58.616189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.460951ms","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-08-14T09:10:58.616244Z","caller":"traceutil/trace.go:171","msg":"trace[1886754595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1401; }","duration":"245.043746ms","start":"2026-08-14T09:10:58.371145Z","end":"2026-08-14T09:10:58.616189Z","steps":["trace[1886754595] 'range keys from in-memory index tree' (duration: 244.677627ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:58.61627Z","caller":"traceutil/trace.go:171","msg":"trace[1057062578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1401; }","duration":"218.601925ms","start":"2026-08-14T09:10:58.397649Z","end":"2026-08-14T09:10:58.616251Z","steps":["trace[1057062578] 'range keys from in-memory index tree' (duration: 218.124274ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:58.843383Z","caller":"traceutil/trace.go:171","msg":"trace[1784447686] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"217.795226ms","start":"2026-08-14T09:10:58.625564Z","end":"2026-08-14T09:10:58.843359Z","steps":["trace[1784447686] 'process raft request' (duration: 207.937016ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:58.843521Z","caller":"traceutil/trace.go:171","msg":"trace[1546735549] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1458; }","duration":"217.803126ms","start":"2026-08-14T09:10:58.625696Z","end":"2026-08-14T09:10:58.843499Z","steps":["trace[1546735549] 'read index received' (duration: 70.625804ms)","trace[1546735549] 'applied index is now lower than readState.Index' (duration: 147.176192ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:58.843619Z","caller":"traceutil/trace.go:171","msg":"trace[1410570938] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"218.020982ms","start":"2026-08-14T09:10:58.625584Z","end":"2026-08-14T09:10:58.843605Z","steps":["trace[1410570938] 'process raft request' (duration: 217.739715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:58.843715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.99525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:58.843781Z","caller":"traceutil/trace.go:171","msg":"trace[196348384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1404; }","duration":"218.092713ms","start":"2026-08-14T09:10:58.625675Z","end":"2026-08-14T09:10:58.843768Z","steps":["trace[196348384] 'agreement among raft nodes before linearized reading' (duration: 217.95648ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:12:38.635378Z","caller":"traceutil/trace.go:171","msg":"trace[1284218334] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"144.218762ms","start":"2026-08-14T09:12:38.491135Z","end":"2026-08-14T09:12:38.635354Z","steps":["trace[1284218334] 'process raft request' (duration: 144.048808ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:33.127504Z","caller":"traceutil/trace.go:171","msg":"trace[544550126] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"167.51192ms","start":"2026-08-14T09:13:32.959712Z","end":"2026-08-14T09:13:33.127224Z","steps":["trace[544550126] 'process raft request' (duration: 167.327096ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:33.339298Z","caller":"traceutil/trace.go:171","msg":"trace[666274197] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"202.526014ms","start":"2026-08-14T09:13:33.136754Z","end":"2026-08-14T09:13:33.33928Z","steps":["trace[666274197] 'process raft request' (duration: 194.665383ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:35.859856Z","caller":"traceutil/trace.go:171","msg":"trace[1998114109] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"104.478065ms","start":"2026-08-14T09:13:35.755358Z","end":"2026-08-14T09:13:35.859836Z","steps":["trace[1998114109] 'process raft request' (duration: 104.330061ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:36.150293Z","caller":"traceutil/trace.go:171","msg":"trace[701248796] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"285.022293ms","start":"2026-08-14T09:13:35.865238Z","end":"2026-08-14T09:13:36.150261Z","steps":["trace[701248796] 'process raft request' (duration: 281.62931ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:36.150425Z","caller":"traceutil/trace.go:171","msg":"trace[974139038] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"283.019384ms","start":"2026-08-14T09:13:35.867373Z","end":"2026-08-14T09:13:36.150393Z","steps":["trace[974139038] 'process raft request' (duration: 282.85776ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:36.150287Z","caller":"traceutil/trace.go:171","msg":"trace[1305378570] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"284.938471ms","start":"2026-08-14T09:13:35.865325Z","end":"2026-08-14T09:13:36.150264Z","steps":["trace[1305378570] 'process raft request' (duration: 284.848039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.155196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.887668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:13:42.155327Z","caller":"traceutil/trace.go:171","msg":"trace[1692416338] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"169.500499ms","start":"2026-08-14T09:13:41.985805Z","end":"2026-08-14T09:13:42.155305Z","steps":["trace[1692416338] 'read index received' (duration: 68.446068ms)","trace[1692416338] 'applied index is now lower than readState.Index' (duration: 101.052471ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:42.15545Z","caller":"traceutil/trace.go:171","msg":"trace[2075332232] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"239.022462ms","start":"2026-08-14T09:13:41.916408Z","end":"2026-08-14T09:13:42.155431Z","steps":["trace[2075332232] 'process raft request' (duration: 137.804467ms)","trace[2075332232] 'compare' (duration: 100.733823ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:42.155595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.444718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:42.155732Z","caller":"traceutil/trace.go:171","msg":"trace[937661892] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2181; }","duration":"105.624743ms","start":"2026-08-14T09:13:42.050089Z","end":"2026-08-14T09:13:42.155714Z","steps":["trace[937661892] 'agreement among raft nodes before linearized reading' (duration: 105.448938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.156627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.825121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2769"} {"level":"info","ts":"2026-08-14T09:13:42.156682Z","caller":"traceutil/trace.go:171","msg":"trace[169779922] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2181; }","duration":"170.952074ms","start":"2026-08-14T09:13:41.985718Z","end":"2026-08-14T09:13:42.15667Z","steps":["trace[169779922] 'agreement among raft nodes before linearized reading' (duration: 169.829567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.672225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228484495,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:13:42.851757Z","caller":"traceutil/trace.go:171","msg":"trace[184242498] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"679.86914ms","start":"2026-08-14T09:13:42.171866Z","end":"2026-08-14T09:13:42.851735Z","steps":["trace[184242498] 'read index received' (duration: 679.621094ms)","trace[184242498] 'applied index is now lower than readState.Index' (duration: 247.116µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:42.85206Z","caller":"traceutil/trace.go:171","msg":"trace[1543002829] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"688.07364ms","start":"2026-08-14T09:13:42.163945Z","end":"2026-08-14T09:13:42.852019Z","steps":["trace[1543002829] 'process raft request' (duration: 687.609128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.852212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.163921Z","time spent":"688.212462ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:42.852277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.815531ms","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-08-14T09:13:42.852369Z","caller":"traceutil/trace.go:171","msg":"trace[1898261245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2182; }","duration":"675.963795ms","start":"2026-08-14T09:13:42.176385Z","end":"2026-08-14T09:13:42.852349Z","steps":["trace[1898261245] 'agreement among raft nodes before linearized reading' (duration: 675.706618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.852349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.65391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:13:42.85242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.176365Z","time spent":"676.045276ms","remote":"127.0.0.1:49626","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-08-14T09:13:42.852427Z","caller":"traceutil/trace.go:171","msg":"trace[1882283669] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2182; }","duration":"141.786023ms","start":"2026-08-14T09:13:42.710627Z","end":"2026-08-14T09:13:42.852413Z","steps":["trace[1882283669] 'agreement among raft nodes before linearized reading' (duration: 141.66382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.852551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.467084ms","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-08-14T09:13:42.852708Z","caller":"traceutil/trace.go:171","msg":"trace[1221092882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2182; }","duration":"680.835513ms","start":"2026-08-14T09:13:42.171842Z","end":"2026-08-14T09:13:42.852677Z","steps":["trace[1221092882] 'agreement among raft nodes before linearized reading' (duration: 680.103265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:42.852831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.17183Z","time spent":"680.973117ms","remote":"127.0.0.1:49626","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-08-14T09:13:43.373596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.232015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T09:13:43.37385Z","caller":"traceutil/trace.go:171","msg":"trace[237392066] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"515.484576ms","start":"2026-08-14T09:13:42.858346Z","end":"2026-08-14T09:13:43.373831Z","steps":["trace[237392066] 'process raft request' (duration: 515.444585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:43.373935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.858335Z","time spent":"515.562639ms","remote":"127.0.0.1:49626","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-08-14T09:13:43.374067Z","caller":"traceutil/trace.go:171","msg":"trace[912350487] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2183; }","duration":"520.701473ms","start":"2026-08-14T09:13:42.853334Z","end":"2026-08-14T09:13:43.374035Z","steps":["trace[912350487] 'process raft request' (duration: 182.897425ms)","trace[912350487] 'compare' (duration: 337.108901ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:43.374171Z","caller":"traceutil/trace.go:171","msg":"trace[402172106] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"516.102662ms","start":"2026-08-14T09:13:42.858033Z","end":"2026-08-14T09:13:43.374136Z","steps":["trace[402172106] 'process raft request' (duration: 515.669211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:43.374377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.858008Z","time spent":"516.254066ms","remote":"127.0.0.1:49626","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-08-14T09:13:43.374941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:42.853314Z","time spent":"520.841597ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":39,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-08-14T09:13:43.783027Z","caller":"traceutil/trace.go:171","msg":"trace[1254721405] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"592.282037ms","start":"2026-08-14T09:13:43.1907Z","end":"2026-08-14T09:13:43.782982Z","steps":["trace[1254721405] 'process raft request' (duration: 591.746294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:43.783225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:43.190674Z","time spent":"592.44718ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:13:43.782701Z","caller":"traceutil/trace.go:171","msg":"trace[2142384169] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"399.298956ms","start":"2026-08-14T09:13:43.383367Z","end":"2026-08-14T09:13:43.782666Z","steps":["trace[2142384169] 'read index received' (duration: 398.662251ms)","trace[2142384169] 'applied index is now lower than readState.Index' (duration: 635.355µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:43.783547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.989163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:13:43.783537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.149047ms","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-08-14T09:13:43.783606Z","caller":"traceutil/trace.go:171","msg":"trace[1240310426] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2186; }","duration":"205.046635ms","start":"2026-08-14T09:13:43.578536Z","end":"2026-08-14T09:13:43.783583Z","steps":["trace[1240310426] 'agreement among raft nodes before linearized reading' (duration: 204.948402ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:43.783623Z","caller":"traceutil/trace.go:171","msg":"trace[1213233108] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2186; }","duration":"400.267ms","start":"2026-08-14T09:13:43.383334Z","end":"2026-08-14T09:13:43.783601Z","steps":["trace[1213233108] 'agreement among raft nodes before linearized reading' (duration: 400.020344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:43.783677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:43.383321Z","time spent":"400.342572ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-14T09:13:43.784192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.244603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T09:13:43.784296Z","caller":"traceutil/trace.go:171","msg":"trace[1442499775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"183.407578ms","start":"2026-08-14T09:13:43.600868Z","end":"2026-08-14T09:13:43.784276Z","steps":["trace[1442499775] 'agreement among raft nodes before linearized reading' (duration: 183.09149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:43.784364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.36761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:43.784493Z","caller":"traceutil/trace.go:171","msg":"trace[2092202466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"161.452673ms","start":"2026-08-14T09:13:43.622954Z","end":"2026-08-14T09:13:43.784407Z","steps":["trace[2092202466] 'agreement among raft nodes before linearized reading' (duration: 160.573311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:44.124392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.843841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:13:44.124673Z","caller":"traceutil/trace.go:171","msg":"trace[1831062257] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"334.424816ms","start":"2026-08-14T09:13:43.790216Z","end":"2026-08-14T09:13:44.12464Z","steps":["trace[1831062257] 'process raft request' (duration: 209.229347ms)","trace[1831062257] 'compare' (duration: 124.733958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:44.124791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:43.790202Z","time spent":"334.533739ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:13:44.124944Z","caller":"traceutil/trace.go:171","msg":"trace[1664671803] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2278; }","duration":"233.087437ms","start":"2026-08-14T09:13:43.891842Z","end":"2026-08-14T09:13:44.124929Z","steps":["trace[1664671803] 'read index received' (duration: 107.527159ms)","trace[1664671803] 'applied index is now lower than readState.Index' (duration: 125.559478ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:44.125068Z","caller":"traceutil/trace.go:171","msg":"trace[244714300] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"334.754644ms","start":"2026-08-14T09:13:43.790276Z","end":"2026-08-14T09:13:44.12503Z","steps":["trace[244714300] 'process raft request' (duration: 334.236951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:44.125206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.369085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T09:13:44.125242Z","caller":"traceutil/trace.go:171","msg":"trace[1735041290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"233.450157ms","start":"2026-08-14T09:13:43.891781Z","end":"2026-08-14T09:13:44.125231Z","steps":["trace[1735041290] 'agreement among raft nodes before linearized reading' (duration: 233.237662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:44.125263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:43.790271Z","time spent":"334.896047ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:13:44.280035Z","caller":"traceutil/trace.go:171","msg":"trace[1136395148] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"120.076125ms","start":"2026-08-14T09:13:44.159931Z","end":"2026-08-14T09:13:44.280008Z","steps":["trace[1136395148] 'read index received' (duration: 72.635079ms)","trace[1136395148] 'applied index is now lower than readState.Index' (duration: 47.439846ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:44.280121Z","caller":"traceutil/trace.go:171","msg":"trace[475737047] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"149.968533ms","start":"2026-08-14T09:13:44.130115Z","end":"2026-08-14T09:13:44.280084Z","steps":["trace[475737047] 'process raft request' (duration: 102.515167ms)","trace[475737047] 'compare' (duration: 47.261141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:44.280229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.27995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:13:44.280266Z","caller":"traceutil/trace.go:171","msg":"trace[1819794453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"120.375132ms","start":"2026-08-14T09:13:44.15988Z","end":"2026-08-14T09:13:44.280255Z","steps":["trace[1819794453] 'agreement among raft nodes before linearized reading' (duration: 120.222828ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:44.474048Z","caller":"traceutil/trace.go:171","msg":"trace[35952285] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"189.073026ms","start":"2026-08-14T09:13:44.284947Z","end":"2026-08-14T09:13:44.47402Z","steps":["trace[35952285] 'process raft request' (duration: 183.399288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:50.347072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228484603,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:13:50.847418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228484603,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:13:51.025452Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192692022s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:13:51.041849Z","caller":"traceutil/trace.go:171","msg":"trace[660826674] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"1.232897821s","start":"2026-08-14T09:13:49.80892Z","end":"2026-08-14T09:13:51.041818Z","steps":["trace[660826674] 'process raft request' (duration: 1.216708197s)","trace[660826674] 'compare' (duration: 15.61914ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:51.042142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.808909Z","time spent":"1.233008274s","remote":"127.0.0.1:49626","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-08-14T09:13:51.063876Z","caller":"traceutil/trace.go:171","msg":"trace[1491119487] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2305; }","duration":"1.217829104s","start":"2026-08-14T09:13:49.846027Z","end":"2026-08-14T09:13:51.063856Z","steps":["trace[1491119487] 'read index received' (duration: 1.179625933s)","trace[1491119487] 'applied index is now lower than readState.Index' (duration: 38.202231ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:51.063991Z","caller":"traceutil/trace.go:171","msg":"trace[618521376] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"585.694817ms","start":"2026-08-14T09:13:50.478282Z","end":"2026-08-14T09:13:51.063976Z","steps":["trace[618521376] 'process raft request' (duration: 585.532523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.064101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:50.478256Z","time spent":"585.769448ms","remote":"127.0.0.1:49626","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-08-14T09:13:51.064218Z","caller":"traceutil/trace.go:171","msg":"trace[1442492361] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"1.230200685s","start":"2026-08-14T09:13:49.833868Z","end":"2026-08-14T09:13:51.064069Z","steps":["trace[1442492361] 'process raft request' (duration: 1.229816076s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.06438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218338207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-08-14T09:13:51.064456Z","caller":"traceutil/trace.go:171","msg":"trace[169501034] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:2215; }","duration":"1.218448319s","start":"2026-08-14T09:13:49.84599Z","end":"2026-08-14T09:13:51.064438Z","steps":["trace[169501034] 'agreement among raft nodes before linearized reading' (duration: 1.218255654s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.064498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.845975Z","time spent":"1.218513771s","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":2402,"request content":"key:\"/registry/clusterroles/cilium-operator\" "} {"level":"warn","ts":"2026-08-14T09:13:51.064581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.833844Z","time spent":"1.230497863s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:51.064671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.776046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:51.064776Z","caller":"traceutil/trace.go:171","msg":"trace[194667488] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2215; }","duration":"153.95534ms","start":"2026-08-14T09:13:50.910796Z","end":"2026-08-14T09:13:51.064751Z","steps":["trace[194667488] 'agreement among raft nodes before linearized reading' (duration: 153.758815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.064658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.409382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:13:51.064602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149101431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:51.064868Z","caller":"traceutil/trace.go:171","msg":"trace[761249720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2215; }","duration":"441.662528ms","start":"2026-08-14T09:13:50.623183Z","end":"2026-08-14T09:13:51.064845Z","steps":["trace[761249720] 'agreement among raft nodes before linearized reading' (duration: 441.32332ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:51.064901Z","caller":"traceutil/trace.go:171","msg":"trace[2128660048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2215; }","duration":"1.149455999s","start":"2026-08-14T09:13:49.915429Z","end":"2026-08-14T09:13:51.064885Z","steps":["trace[2128660048] 'agreement among raft nodes before linearized reading' (duration: 1.149117221s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.064947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.915408Z","time spent":"1.14952258s","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:13:51.064906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.151635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T09:13:51.065112Z","caller":"traceutil/trace.go:171","msg":"trace[283668721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2215; }","duration":"753.389841ms","start":"2026-08-14T09:13:50.311705Z","end":"2026-08-14T09:13:51.065094Z","steps":["trace[283668721] 'agreement among raft nodes before linearized reading' (duration: 752.737555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.065159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:50.311688Z","time spent":"753.459343ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:13:51.065419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.215869ms","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:531"} {"level":"warn","ts":"2026-08-14T09:13:51.065488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.98296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:13:51.0655Z","caller":"traceutil/trace.go:171","msg":"trace[1334165920] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2215; }","duration":"907.335962ms","start":"2026-08-14T09:13:50.158148Z","end":"2026-08-14T09:13:51.065484Z","steps":["trace[1334165920] 'agreement among raft nodes before linearized reading' (duration: 906.491921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.065548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:50.158128Z","time spent":"907.408363ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-14T09:13:51.065596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185549358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-08-14T09:13:51.065655Z","caller":"traceutil/trace.go:171","msg":"trace[82773911] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:2215; }","duration":"1.18560798s","start":"2026-08-14T09:13:49.880036Z","end":"2026-08-14T09:13:51.065644Z","steps":["trace[82773911] 'agreement among raft nodes before linearized reading' (duration: 1.184703907s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.065702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.87999Z","time spent":"1.185701252s","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":1,"response size":1677,"request content":"key:\"/registry/clusterroles/cilium\" "} {"level":"info","ts":"2026-08-14T09:13:51.065544Z","caller":"traceutil/trace.go:171","msg":"trace[1125648468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2215; }","duration":"567.085814ms","start":"2026-08-14T09:13:50.498441Z","end":"2026-08-14T09:13:51.065527Z","steps":["trace[1125648468] 'agreement among raft nodes before linearized reading' (duration: 566.274244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.065836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:50.498418Z","time spent":"567.395891ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:13:51.06602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:50.623163Z","time spent":"441.748061ms","remote":"127.0.0.1:49318","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-08-14T09:13:51.065958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19586507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T09:13:51.066238Z","caller":"traceutil/trace.go:171","msg":"trace[1727196176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2215; }","duration":"1.196172007s","start":"2026-08-14T09:13:49.870048Z","end":"2026-08-14T09:13:51.06622Z","steps":["trace[1727196176] 'agreement among raft nodes before linearized reading' (duration: 1.194296061s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:51.066428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:49.870033Z","time spent":"1.196378182s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:13:51.626375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527078228484617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:13:52.172707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.109932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-08-14T09:13:52.17286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.222355ms","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-08-14T09:13:52.172961Z","caller":"traceutil/trace.go:171","msg":"trace[1142133512] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2216; }","duration":"585.336737ms","start":"2026-08-14T09:13:51.587609Z","end":"2026-08-14T09:13:52.172946Z","steps":["trace[1142133512] 'range keys from in-memory index tree' (duration: 585.207544ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.172968Z","caller":"traceutil/trace.go:171","msg":"trace[1733022814] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"1.101161422s","start":"2026-08-14T09:13:51.071788Z","end":"2026-08-14T09:13:52.172949Z","steps":["trace[1733022814] 'process raft request' (duration: 1.101117951s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.173037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.071781Z","time spent":"1.101221154s","remote":"127.0.0.1:49626","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-08-14T09:13:52.173242Z","caller":"traceutil/trace.go:171","msg":"trace[2014443950] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"1.102145566s","start":"2026-08-14T09:13:51.071082Z","end":"2026-08-14T09:13:52.173227Z","steps":["trace[2014443950] 'process raft request' (duration: 968.424899ms)","trace[2014443950] 'compare' (duration: 132.832596ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:52.173308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.071072Z","time spent":"1.102207068s","remote":"127.0.0.1:49626","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-08-14T09:13:52.173444Z","caller":"traceutil/trace.go:171","msg":"trace[1969958575] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"1.102243789s","start":"2026-08-14T09:13:51.071188Z","end":"2026-08-14T09:13:52.173432Z","steps":["trace[1969958575] 'process raft request' (duration: 1.101627634s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.173504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.071181Z","time spent":"1.10229536s","remote":"127.0.0.1:49626","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-08-14T09:13:52.264525Z","caller":"traceutil/trace.go:171","msg":"trace[1782247980] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"1.067569514s","start":"2026-08-14T09:13:51.19692Z","end":"2026-08-14T09:13:52.26449Z","steps":["trace[1782247980] 'process raft request' (duration: 1.067284537s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.264749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194116397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:13:52.264802Z","caller":"traceutil/trace.go:171","msg":"trace[76592502] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2220; }","duration":"1.194207969s","start":"2026-08-14T09:13:51.070582Z","end":"2026-08-14T09:13:52.26479Z","steps":["trace[76592502] 'agreement among raft nodes before linearized reading' (duration: 1.194007224s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.264839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.070574Z","time spent":"1.194254101s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-08-14T09:13:52.26463Z","caller":"traceutil/trace.go:171","msg":"trace[252313309] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2309; }","duration":"1.13884757s","start":"2026-08-14T09:13:51.125511Z","end":"2026-08-14T09:13:52.264358Z","steps":["trace[252313309] 'read index received' (duration: 914.013534ms)","trace[252313309] 'applied index is now lower than readState.Index' (duration: 224.832866ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:52.264885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.196897Z","time spent":"1.067880711s","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7010,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:52.265113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.661934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-14T09:13:52.265164Z","caller":"traceutil/trace.go:171","msg":"trace[870846420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2220; }","duration":"798.762666ms","start":"2026-08-14T09:13:51.466388Z","end":"2026-08-14T09:13:52.26515Z","steps":["trace[870846420] 'agreement among raft nodes before linearized reading' (duration: 798.616933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.265168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.43768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-14T09:13:52.265188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.34905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-14T09:13:52.2652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.466367Z","time spent":"798.824237ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:13:52.265201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072149955s","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:524"} {"level":"warn","ts":"2026-08-14T09:13:52.265212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062288566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-14T09:13:52.265239Z","caller":"traceutil/trace.go:171","msg":"trace[389015673] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2220; }","duration":"399.560463ms","start":"2026-08-14T09:13:51.865659Z","end":"2026-08-14T09:13:52.26522Z","steps":["trace[389015673] 'agreement among raft nodes before linearized reading' (duration: 399.333248ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.265242Z","caller":"traceutil/trace.go:171","msg":"trace[18157554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2220; }","duration":"847.447282ms","start":"2026-08-14T09:13:51.417781Z","end":"2026-08-14T09:13:52.265228Z","steps":["trace[18157554] 'agreement among raft nodes before linearized reading' (duration: 847.203316ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.265252Z","caller":"traceutil/trace.go:171","msg":"trace[1589314865] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2220; }","duration":"1.072219598s","start":"2026-08-14T09:13:51.19302Z","end":"2026-08-14T09:13:52.265239Z","steps":["trace[1589314865] 'agreement among raft nodes before linearized reading' (duration: 1.072100625s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.265257Z","caller":"traceutil/trace.go:171","msg":"trace[326293456] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:2220; }","duration":"1.062363248s","start":"2026-08-14T09:13:51.202881Z","end":"2026-08-14T09:13:52.265245Z","steps":["trace[326293456] 'agreement among raft nodes before linearized reading' (duration: 1.062281806s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.265286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.19301Z","time spent":"1.072267379s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-14T09:13:52.265285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.417732Z","time spent":"847.544855ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:13:52.265285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.865639Z","time spent":"399.637725ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-14T09:13:52.265291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.202864Z","time spent":"1.062419579s","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":576,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"warn","ts":"2026-08-14T09:13:52.265492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093152817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-08-14T09:13:52.265576Z","caller":"traceutil/trace.go:171","msg":"trace[2016661297] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2220; }","duration":"1.09327886s","start":"2026-08-14T09:13:51.172278Z","end":"2026-08-14T09:13:52.265557Z","steps":["trace[2016661297] 'agreement among raft nodes before linearized reading' (duration: 1.093030854s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.26562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.172265Z","time spent":"1.093347042s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-14T09:13:52.268329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133595412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-08-14T09:13:52.268433Z","caller":"traceutil/trace.go:171","msg":"trace[133475309] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:2220; }","duration":"1.133708135s","start":"2026-08-14T09:13:51.134703Z","end":"2026-08-14T09:13:52.268411Z","steps":["trace[133475309] 'agreement among raft nodes before linearized reading' (duration: 1.13021641s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.268487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:51.134661Z","time spent":"1.133810237s","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":2402,"request content":"key:\"/registry/clusterroles/cilium-operator\" "} {"level":"warn","ts":"2026-08-14T09:13:52.541038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.842396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:13:52.541221Z","caller":"traceutil/trace.go:171","msg":"trace[1876671543] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"269.054644ms","start":"2026-08-14T09:13:52.27211Z","end":"2026-08-14T09:13:52.541164Z","steps":["trace[1876671543] 'process raft request' (duration: 73.994682ms)","trace[1876671543] 'compare' (duration: 194.649072ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:52.608221Z","caller":"traceutil/trace.go:171","msg":"trace[61477122] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"335.831041ms","start":"2026-08-14T09:13:52.272371Z","end":"2026-08-14T09:13:52.608202Z","steps":["trace[61477122] 'process raft request' (duration: 335.760929ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.60828Z","caller":"traceutil/trace.go:171","msg":"trace[669971909] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"336.096257ms","start":"2026-08-14T09:13:52.272148Z","end":"2026-08-14T09:13:52.608244Z","steps":["trace[669971909] 'process raft request' (duration: 335.846461ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.608297Z","caller":"traceutil/trace.go:171","msg":"trace[1594295501] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"336.053485ms","start":"2026-08-14T09:13:52.272216Z","end":"2026-08-14T09:13:52.608269Z","steps":["trace[1594295501] 'process raft request' (duration: 335.881821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.608353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.272365Z","time spent":"335.924183ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:52.608409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.272207Z","time spent":"336.142828ms","remote":"127.0.0.1:49626","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-08-14T09:13:52.608456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.272136Z","time spent":"336.212949ms","remote":"127.0.0.1:49626","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-08-14T09:13:52.608672Z","caller":"traceutil/trace.go:171","msg":"trace[320069165] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"336.288072ms","start":"2026-08-14T09:13:52.272371Z","end":"2026-08-14T09:13:52.608659Z","steps":["trace[320069165] 'process raft request' (duration: 335.79798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.608732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.272364Z","time spent":"336.338353ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:13:52.608656Z","caller":"traceutil/trace.go:171","msg":"trace[647011069] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2313; }","duration":"336.384534ms","start":"2026-08-14T09:13:52.272237Z","end":"2026-08-14T09:13:52.608622Z","steps":["trace[647011069] 'read index received' (duration: 73.87523ms)","trace[647011069] 'applied index is now lower than readState.Index' (duration: 262.505534ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:52.609029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.768463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-08-14T09:13:52.609171Z","caller":"traceutil/trace.go:171","msg":"trace[1263083584] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:2225; }","duration":"337.089991ms","start":"2026-08-14T09:13:52.272016Z","end":"2026-08-14T09:13:52.609106Z","steps":["trace[1263083584] 'agreement among raft nodes before linearized reading' (duration: 336.872795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.609271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.272005Z","time spent":"337.244885ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":2402,"request content":"key:\"/registry/clusterroles/cilium-operator\" "} {"level":"info","ts":"2026-08-14T09:13:52.877551Z","caller":"traceutil/trace.go:171","msg":"trace[732445229] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"268.662294ms","start":"2026-08-14T09:13:52.608865Z","end":"2026-08-14T09:13:52.877528Z","steps":["trace[732445229] 'read index received' (duration: 268.397397ms)","trace[732445229] 'applied index is now lower than readState.Index' (duration: 263.867µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:52.877775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.239315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:52.877773Z","caller":"traceutil/trace.go:171","msg":"trace[1283613239] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"508.486786ms","start":"2026-08-14T09:13:52.36922Z","end":"2026-08-14T09:13:52.877706Z","steps":["trace[1283613239] 'process raft request' (duration: 508.110177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.877873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.779663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:52.877818Z","caller":"traceutil/trace.go:171","msg":"trace[902904179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2226; }","duration":"257.317887ms","start":"2026-08-14T09:13:52.620488Z","end":"2026-08-14T09:13:52.877806Z","steps":["trace[902904179] 'agreement among raft nodes before linearized reading' (duration: 257.232025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.877926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.782386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:13:52.877955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.369192Z","time spent":"508.6713ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:52.877933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.87266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-08-14T09:13:52.878009Z","caller":"traceutil/trace.go:171","msg":"trace[1003758731] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2226; }","duration":"199.93079ms","start":"2026-08-14T09:13:52.67806Z","end":"2026-08-14T09:13:52.877991Z","steps":["trace[1003758731] 'agreement among raft nodes before linearized reading' (duration: 199.763256ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.878063Z","caller":"traceutil/trace.go:171","msg":"trace[1610008329] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:2226; }","duration":"189.042334ms","start":"2026-08-14T09:13:52.688994Z","end":"2026-08-14T09:13:52.878037Z","steps":["trace[1610008329] 'agreement among raft nodes before linearized reading' (duration: 188.809578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.877789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.444887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-14T09:13:52.878409Z","caller":"traceutil/trace.go:171","msg":"trace[1102508057] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:2226; }","duration":"537.115013ms","start":"2026-08-14T09:13:52.341275Z","end":"2026-08-14T09:13:52.87839Z","steps":["trace[1102508057] 'agreement among raft nodes before linearized reading' (duration: 536.367884ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:52.878408Z","caller":"traceutil/trace.go:171","msg":"trace[1441706437] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2226; }","duration":"594.004769ms","start":"2026-08-14T09:13:52.283887Z","end":"2026-08-14T09:13:52.877892Z","steps":["trace[1441706437] 'agreement among raft nodes before linearized reading' (duration: 593.757672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:52.878476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.34126Z","time spent":"537.198195ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":576,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"warn","ts":"2026-08-14T09:13:52.878495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:52.283838Z","time spent":"594.640764ms","remote":"127.0.0.1:36982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:13:53.055987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.490795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T09:13:53.056063Z","caller":"traceutil/trace.go:171","msg":"trace[1871592582] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2319; }","duration":"103.802988ms","start":"2026-08-14T09:13:52.952248Z","end":"2026-08-14T09:13:53.056051Z","steps":["trace[1871592582] 'read index received' (duration: 31.251µs)","trace[1871592582] 'applied index is now lower than readState.Index' (duration: 103.770897ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:53.056206Z","caller":"traceutil/trace.go:171","msg":"trace[1334538964] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2227; }","duration":"169.377066ms","start":"2026-08-14T09:13:52.886821Z","end":"2026-08-14T09:13:53.056198Z","steps":["trace[1334538964] 'process raft request' (duration: 32.607194ms)","trace[1334538964] 'compare' (duration: 136.395133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:53.056273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.004763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-08-14T09:13:53.056406Z","caller":"traceutil/trace.go:171","msg":"trace[1566324244] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:2227; }","duration":"104.101346ms","start":"2026-08-14T09:13:52.952216Z","end":"2026-08-14T09:13:53.056318Z","steps":["trace[1566324244] 'agreement among raft nodes before linearized reading' (duration: 103.983123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.078969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.169719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-08-14T09:13:53.079057Z","caller":"traceutil/trace.go:171","msg":"trace[591752239] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2228; }","duration":"116.323383ms","start":"2026-08-14T09:13:52.962715Z","end":"2026-08-14T09:13:53.079038Z","steps":["trace[591752239] 'agreement among raft nodes before linearized reading' (duration: 116.051337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.445796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.451928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:13:53.44593Z","caller":"traceutil/trace.go:171","msg":"trace[336766217] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"287.282136ms","start":"2026-08-14T09:13:53.158625Z","end":"2026-08-14T09:13:53.445907Z","steps":["trace[336766217] 'read index received' (duration: 64.595733ms)","trace[336766217] 'applied index is now lower than readState.Index' (duration: 222.684313ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:53.446189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.538783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-08-14T09:13:53.446281Z","caller":"traceutil/trace.go:171","msg":"trace[1019811241] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"313.020253ms","start":"2026-08-14T09:13:53.133245Z","end":"2026-08-14T09:13:53.446265Z","steps":["trace[1019811241] 'process raft request' (duration: 89.992502ms)","trace[1019811241] 'compare' (duration: 222.286163ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:53.446365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:53.133223Z","time spent":"313.104176ms","remote":"127.0.0.1:49626","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-08-14T09:13:53.446281Z","caller":"traceutil/trace.go:171","msg":"trace[309653098] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2229; }","duration":"287.675076ms","start":"2026-08-14T09:13:53.158587Z","end":"2026-08-14T09:13:53.446262Z","steps":["trace[309653098] 'agreement among raft nodes before linearized reading' (duration: 287.44407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.446433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.700623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-08-14T09:13:53.446525Z","caller":"traceutil/trace.go:171","msg":"trace[460128430] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:2229; }","duration":"277.817746ms","start":"2026-08-14T09:13:53.168683Z","end":"2026-08-14T09:13:53.446501Z","steps":["trace[460128430] 'agreement among raft nodes before linearized reading' (duration: 277.57525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.729906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.867388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:53.729988Z","caller":"traceutil/trace.go:171","msg":"trace[1856701012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2229; }","duration":"107.97753ms","start":"2026-08-14T09:13:53.621988Z","end":"2026-08-14T09:13:53.729965Z","steps":["trace[1856701012] 'range keys from in-memory index tree' (duration: 107.777526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.729785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.02462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-08-14T09:13:53.730395Z","caller":"traceutil/trace.go:171","msg":"trace[959284304] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:2229; }","duration":"117.720888ms","start":"2026-08-14T09:13:53.612656Z","end":"2026-08-14T09:13:53.730377Z","steps":["trace[959284304] 'range keys from in-memory index tree' (duration: 116.930108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:53.730881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.013013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-08-14T09:13:53.730975Z","caller":"traceutil/trace.go:171","msg":"trace[246802281] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:2229; }","duration":"129.131936ms","start":"2026-08-14T09:13:53.601824Z","end":"2026-08-14T09:13:53.730956Z","steps":["trace[246802281] 'range keys from in-memory index tree' (duration: 127.953156ms)"],"step_count":1}