{"level":"warn","ts":"2026-05-15T15:22:38.736211Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T15:22:38.736345Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-15T15:22:38.738002Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T15:22:38.738033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-15T15:22:38.738468Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-15T15:22:38.739947Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-15T15:22:38.740242Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-15T15:22:38.746332Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.466451ms"} {"level":"info","ts":"2026-05-15T15:22:38.752215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-15T15:22:38.752608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T15:22:38.752671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-15T15:22:38.753018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T15:22:38.753215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-15T15:22:38.753905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-15T15:22:38.762884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T15:22:38.766228Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T15:22:38.767971Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-15T15:22:38.77179Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T15:22:38.771949Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T15:22:38.772223Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T15:22:38.772264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T15:22:38.772277Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T15:22:38.779258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-15T15:22:38.779629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-15T15:22:38.78292Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-15T15:22:38.783123Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T15:22:38.783163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T15:22:38.783137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-15T15:22:38.783321Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-15T15:22:39.255313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T15:22:39.255379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T15:22:39.255408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-15T15:22:39.255431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-15T15:22:39.255491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-15T15:22:39.25552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-15T15:22:39.25557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-15T15:22:39.296457Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:22:39.304376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T15:22:39.304382Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T15:22:39.304451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T15:22:39.304835Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T15:22:39.304884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:22:39.304905Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T15:22:39.305168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:22:39.305237Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:22:39.307089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T15:22:39.310659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-15T15:22:51.225441Z","caller":"traceutil/trace.go:171","msg":"trace[1883582401] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"222.712169ms","start":"2026-05-15T15:22:51.002707Z","end":"2026-05-15T15:22:51.225419Z","steps":["trace[1883582401] 'process raft request' (duration: 222.527894ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:22:51.255135Z","caller":"traceutil/trace.go:171","msg":"trace[1912821108] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"115.369056ms","start":"2026-05-15T15:22:51.139739Z","end":"2026-05-15T15:22:51.255108Z","steps":["trace[1912821108] 'process raft request' (duration: 115.202112ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:02.618651Z","caller":"traceutil/trace.go:171","msg":"trace[1255078531] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"235.871225ms","start":"2026-05-15T15:23:02.38275Z","end":"2026-05-15T15:23:02.618621Z","steps":["trace[1255078531] 'read index received' (duration: 235.694ms)","trace[1255078531] 'applied index is now lower than readState.Index' (duration: 175.535µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:23:02.618875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.095521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:02.618927Z","caller":"traceutil/trace.go:171","msg":"trace[486356157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"236.198453ms","start":"2026-05-15T15:23:02.382721Z","end":"2026-05-15T15:23:02.618919Z","steps":["trace[486356157] 'agreement among raft nodes before linearized reading' (duration: 236.08091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:03.952102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037808056,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:23:04.095677Z","caller":"traceutil/trace.go:171","msg":"trace[64181535] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"644.237153ms","start":"2026-05-15T15:23:03.451418Z","end":"2026-05-15T15:23:04.095655Z","steps":["trace[64181535] 'read index received' (duration: 644.105279ms)","trace[64181535] 'applied index is now lower than readState.Index' (duration: 130.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:04.095828Z","caller":"traceutil/trace.go:171","msg":"trace[996799227] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"815.469481ms","start":"2026-05-15T15:23:03.280323Z","end":"2026-05-15T15:23:04.095793Z","steps":["trace[996799227] 'process raft request' (duration: 815.192754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:04.095913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.479268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T15:23:04.095979Z","caller":"traceutil/trace.go:171","msg":"trace[303850326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"644.584881ms","start":"2026-05-15T15:23:03.45137Z","end":"2026-05-15T15:23:04.095954Z","steps":["trace[303850326] 'agreement among raft nodes before linearized reading' (duration: 644.458308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:04.096014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:03.451349Z","time spent":"644.656722ms","remote":"127.0.0.1:58538","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-05-15T15:23:04.096488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:03.280308Z","time spent":"815.603074ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:04.134548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.071996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T15:23:04.134619Z","caller":"traceutil/trace.go:171","msg":"trace[565980977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"513.193288ms","start":"2026-05-15T15:23:03.62141Z","end":"2026-05-15T15:23:04.134603Z","steps":["trace[565980977] 'agreement among raft nodes before linearized reading' (duration: 513.064485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:04.134656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:03.621362Z","time spent":"513.286661ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:23:07.492658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.602495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:07.492759Z","caller":"traceutil/trace.go:171","msg":"trace[1538519101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"111.747999ms","start":"2026-05-15T15:23:07.380987Z","end":"2026-05-15T15:23:07.492735Z","steps":["trace[1538519101] 'range keys from in-memory index tree' (duration: 111.495513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:13.520803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037808319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:23:14.022011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037808319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:23:14.05351Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056313637s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T15:23:14.053852Z","caller":"traceutil/trace.go:171","msg":"trace[1582512975] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.056762308s","start":"2026-05-15T15:23:12.997063Z","end":"2026-05-15T15:23:14.053826Z","steps":["trace[1582512975] 'process raft request' (duration: 1.056648265s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.053977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:12.997039Z","time spent":"1.056876551s","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:23:14.064996Z","caller":"traceutil/trace.go:171","msg":"trace[1773315301] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"1.044979874s","start":"2026-05-15T15:23:13.01999Z","end":"2026-05-15T15:23:14.06497Z","steps":["trace[1773315301] 'read index received' (duration: 1.033769905s)","trace[1773315301] 'applied index is now lower than readState.Index' (duration: 11.208809ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:14.065049Z","caller":"traceutil/trace.go:171","msg":"trace[1804325652] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"664.777296ms","start":"2026-05-15T15:23:13.400249Z","end":"2026-05-15T15:23:14.065026Z","steps":["trace[1804325652] 'process raft request' (duration: 664.614533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.065172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:13.400217Z","time spent":"664.894389ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:23:14.065312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045312013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T15:23:14.065404Z","caller":"traceutil/trace.go:171","msg":"trace[1839557636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"1.045402794s","start":"2026-05-15T15:23:13.019955Z","end":"2026-05-15T15:23:14.065358Z","steps":["trace[1839557636] 'agreement among raft nodes before linearized reading' (duration: 1.045145298s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.065465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:13.019941Z","time spent":"1.045510008s","remote":"127.0.0.1:58538","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-05-15T15:23:14.06936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.137249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:14.069437Z","caller":"traceutil/trace.go:171","msg":"trace[1249054638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:585; }","duration":"868.243471ms","start":"2026-05-15T15:23:13.201179Z","end":"2026-05-15T15:23:14.069423Z","steps":["trace[1249054638] 'agreement among raft nodes before linearized reading' (duration: 868.122758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.069471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:13.201166Z","time spent":"868.296883ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-15T15:23:14.069638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.720186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:14.069673Z","caller":"traceutil/trace.go:171","msg":"trace[1747713802] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:585; }","duration":"273.798708ms","start":"2026-05-15T15:23:13.795864Z","end":"2026-05-15T15:23:14.069662Z","steps":["trace[1747713802] 'agreement among raft nodes before linearized reading' (duration: 273.751057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.06978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.738027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:14.06983Z","caller":"traceutil/trace.go:171","msg":"trace[1846125114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"689.792218ms","start":"2026-05-15T15:23:13.380026Z","end":"2026-05-15T15:23:14.069818Z","steps":["trace[1846125114] 'agreement among raft nodes before linearized reading' (duration: 689.714346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.070029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.073588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:23:14.069988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:13.379975Z","time spent":"690.001993ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:23:14.070069Z","caller":"traceutil/trace.go:171","msg":"trace[1580793016] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:585; }","duration":"474.1484ms","start":"2026-05-15T15:23:13.595912Z","end":"2026-05-15T15:23:14.07006Z","steps":["trace[1580793016] 'agreement among raft nodes before linearized reading' (duration: 474.092968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:14.070505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:13.595892Z","time spent":"474.60182ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-15T15:23:20.620655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037808535,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:23:20.826232Z","caller":"traceutil/trace.go:171","msg":"trace[652392775] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"706.045669ms","start":"2026-05-15T15:23:20.12016Z","end":"2026-05-15T15:23:20.826205Z","steps":["trace[652392775] 'read index received' (duration: 705.828094ms)","trace[652392775] 'applied index is now lower than readState.Index' (duration: 216.565µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:20.826356Z","caller":"traceutil/trace.go:171","msg":"trace[674793803] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"725.791694ms","start":"2026-05-15T15:23:20.100542Z","end":"2026-05-15T15:23:20.826334Z","steps":["trace[674793803] 'process raft request' (duration: 725.526738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:20.826534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.165928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:23:20.82658Z","caller":"traceutil/trace.go:171","msg":"trace[1920554117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"560.22661ms","start":"2026-05-15T15:23:20.266339Z","end":"2026-05-15T15:23:20.826566Z","steps":["trace[1920554117] 'agreement among raft nodes before linearized reading' (duration: 560.104147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:20.826611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.266273Z","time spent":"560.329893ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:23:20.826662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.100514Z","time spent":"726.019719ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:20.826685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.517781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T15:23:20.826781Z","caller":"traceutil/trace.go:171","msg":"trace[931735666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"652.756877ms","start":"2026-05-15T15:23:20.174004Z","end":"2026-05-15T15:23:20.82676Z","steps":["trace[931735666] 'agreement among raft nodes before linearized reading' (duration: 652.406258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:20.82673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.36686ms","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-05-15T15:23:20.826833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.173985Z","time spent":"652.837288ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T15:23:20.826859Z","caller":"traceutil/trace.go:171","msg":"trace[1035110560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"559.543923ms","start":"2026-05-15T15:23:20.267292Z","end":"2026-05-15T15:23:20.826836Z","steps":["trace[1035110560] 'agreement among raft nodes before linearized reading' (duration: 559.126353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:20.826943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.267281Z","time spent":"559.651546ms","remote":"127.0.0.1:58538","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-05-15T15:23:20.827125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.970241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T15:23:20.827165Z","caller":"traceutil/trace.go:171","msg":"trace[278659610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"707.047263ms","start":"2026-05-15T15:23:20.120105Z","end":"2026-05-15T15:23:20.827152Z","steps":["trace[278659610] 'agreement among raft nodes before linearized reading' (duration: 706.225363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:20.827194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.12008Z","time spent":"707.107425ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-15T15:23:20.954384Z","caller":"traceutil/trace.go:171","msg":"trace[109260679] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"121.950764ms","start":"2026-05-15T15:23:20.832406Z","end":"2026-05-15T15:23:20.954357Z","steps":["trace[109260679] 'process raft request' (duration: 91.280076ms)","trace[109260679] 'compare' (duration: 30.479593ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:20.95469Z","caller":"traceutil/trace.go:171","msg":"trace[391351159] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"122.011515ms","start":"2026-05-15T15:23:20.832661Z","end":"2026-05-15T15:23:20.954673Z","steps":["trace[391351159] 'process raft request' (duration: 121.638986ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:20.95488Z","caller":"traceutil/trace.go:171","msg":"trace[597750519] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"122.140269ms","start":"2026-05-15T15:23:20.832724Z","end":"2026-05-15T15:23:20.954865Z","steps":["trace[597750519] 'process raft request' (duration: 121.881623ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:21.277353Z","caller":"traceutil/trace.go:171","msg":"trace[1520286653] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"308.263366ms","start":"2026-05-15T15:23:20.969067Z","end":"2026-05-15T15:23:21.277331Z","steps":["trace[1520286653] 'process raft request' (duration: 308.125273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:21.277509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:20.969053Z","time spent":"308.390439ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:21.809402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.113717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:21.809502Z","caller":"traceutil/trace.go:171","msg":"trace[1447591982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"427.317422ms","start":"2026-05-15T15:23:21.382161Z","end":"2026-05-15T15:23:21.809479Z","steps":["trace[1447591982] 'range keys from in-memory index tree' (duration: 426.921372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:21.809577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:21.382142Z","time spent":"427.419985ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:23:23.070905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.134684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T15:23:23.070977Z","caller":"traceutil/trace.go:171","msg":"trace[1283381282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"239.222926ms","start":"2026-05-15T15:23:22.831738Z","end":"2026-05-15T15:23:23.070961Z","steps":["trace[1283381282] 'range keys from in-memory index tree' (duration: 238.928019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.071007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:22.500808Z","time spent":"570.19344ms","remote":"127.0.0.1:58574","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-15T15:23:23.07119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.415311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:23:23.071218Z","caller":"traceutil/trace.go:171","msg":"trace[1058330564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"212.477983ms","start":"2026-05-15T15:23:22.858732Z","end":"2026-05-15T15:23:23.07121Z","steps":["trace[1058330564] 'range keys from in-memory index tree' (duration: 212.263827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.071388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.011449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T15:23:23.071423Z","caller":"traceutil/trace.go:171","msg":"trace[534444824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"113.072781ms","start":"2026-05-15T15:23:22.958339Z","end":"2026-05-15T15:23:23.071412Z","steps":["trace[534444824] 'range keys from in-memory index tree' (duration: 112.899397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.071582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.20337ms","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-05-15T15:23:23.071571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.867415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-15T15:23:23.071607Z","caller":"traceutil/trace.go:171","msg":"trace[1877965705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"112.267001ms","start":"2026-05-15T15:23:22.959332Z","end":"2026-05-15T15:23:23.071599Z","steps":["trace[1877965705] 'range keys from in-memory index tree' (duration: 112.090637ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:23.071641Z","caller":"traceutil/trace.go:171","msg":"trace[1659950065] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:648; }","duration":"737.982088ms","start":"2026-05-15T15:23:22.333641Z","end":"2026-05-15T15:23:23.071623Z","steps":["trace[1659950065] 'range keys from in-memory index tree' (duration: 737.696841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.071684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:22.333617Z","time spent":"738.058379ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-15T15:23:23.071751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.525444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T15:23:23.071791Z","caller":"traceutil/trace.go:171","msg":"trace[203253000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:648; }","duration":"111.590916ms","start":"2026-05-15T15:23:22.960187Z","end":"2026-05-15T15:23:23.071778Z","steps":["trace[203253000] 'range keys from in-memory index tree' (duration: 111.38138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.768969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.074855Z","time spent":"694.108941ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-15T15:23:23.986397Z","caller":"traceutil/trace.go:171","msg":"trace[928895722] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"910.355005ms","start":"2026-05-15T15:23:23.076025Z","end":"2026-05-15T15:23:23.98638Z","steps":["trace[928895722] 'process raft request' (duration: 910.223742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.986508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.07601Z","time spent":"910.443087ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:23.986657Z","caller":"traceutil/trace.go:171","msg":"trace[815729541] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"909.996146ms","start":"2026-05-15T15:23:23.076643Z","end":"2026-05-15T15:23:23.986639Z","steps":["trace[815729541] 'process raft request' (duration: 909.856183ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:23.98668Z","caller":"traceutil/trace.go:171","msg":"trace[790418019] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"909.945725ms","start":"2026-05-15T15:23:23.076711Z","end":"2026-05-15T15:23:23.986657Z","steps":["trace[790418019] 'process raft request' (duration: 909.864083ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:23.986722Z","caller":"traceutil/trace.go:171","msg":"trace[1300969564] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"910.428306ms","start":"2026-05-15T15:23:23.076263Z","end":"2026-05-15T15:23:23.986692Z","steps":["trace[1300969564] 'process raft request' (duration: 910.085048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.986778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.076634Z","time spent":"910.099648ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:23.986795Z","caller":"traceutil/trace.go:171","msg":"trace[546938738] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:671; }","duration":"603.928021ms","start":"2026-05-15T15:23:23.382855Z","end":"2026-05-15T15:23:23.986783Z","steps":["trace[546938738] 'read index received' (duration: 385.978327ms)","trace[546938738] 'applied index is now lower than readState.Index' (duration: 217.948614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:23:23.986847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.996824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:23:23.986836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.076705Z","time spent":"910.042618ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:23.986873Z","caller":"traceutil/trace.go:171","msg":"trace[2090221280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"604.055415ms","start":"2026-05-15T15:23:23.38281Z","end":"2026-05-15T15:23:23.986865Z","steps":["trace[2090221280] 'agreement among raft nodes before linearized reading' (duration: 604.008884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.986865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.076255Z","time spent":"910.528259ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:23.986905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.382794Z","time spent":"604.102126ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:23:23.986896Z","caller":"traceutil/trace.go:171","msg":"trace[1627727181] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"910.102039ms","start":"2026-05-15T15:23:23.076775Z","end":"2026-05-15T15:23:23.986877Z","steps":["trace[1627727181] 'process raft request' (duration: 909.845273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:23.98698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:23.076769Z","time spent":"910.16722ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:23.988929Z","caller":"traceutil/trace.go:171","msg":"trace[7961834] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"218.291913ms","start":"2026-05-15T15:23:23.770621Z","end":"2026-05-15T15:23:23.988913Z","steps":["trace[7961834] 'process raft request' (duration: 218.16797ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:33.38187Z","caller":"traceutil/trace.go:171","msg":"trace[1916251762] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"334.686294ms","start":"2026-05-15T15:23:33.047163Z","end":"2026-05-15T15:23:33.381849Z","steps":["trace[1916251762] 'process raft request' (duration: 334.573351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:33.382041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:33.047148Z","time spent":"334.836048ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:39.472669Z","caller":"traceutil/trace.go:171","msg":"trace[1402686854] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"297.911203ms","start":"2026-05-15T15:23:39.174735Z","end":"2026-05-15T15:23:39.472646Z","steps":["trace[1402686854] 'process raft request' (duration: 297.78828ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:39.653192Z","caller":"traceutil/trace.go:171","msg":"trace[1922401869] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"169.243277ms","start":"2026-05-15T15:23:39.48389Z","end":"2026-05-15T15:23:39.653133Z","steps":["trace[1922401869] 'process raft request' (duration: 169.117134ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.291241Z","caller":"traceutil/trace.go:171","msg":"trace[1724023254] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"234.022107ms","start":"2026-05-15T15:23:40.057172Z","end":"2026-05-15T15:23:40.291194Z","steps":["trace[1724023254] 'read index received' (duration: 233.910494ms)","trace[1724023254] 'applied index is now lower than readState.Index' (duration: 110.563µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:40.291466Z","caller":"traceutil/trace.go:171","msg":"trace[2006685616] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"627.442412ms","start":"2026-05-15T15:23:39.664013Z","end":"2026-05-15T15:23:40.291455Z","steps":["trace[2006685616] 'process raft request' (duration: 627.063493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:40.291559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:39.663992Z","time spent":"627.509284ms","remote":"127.0.0.1:58236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2859,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:23:40.291792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.426032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-15T15:23:40.29181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.186475ms","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-05-15T15:23:40.291856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.689013ms","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-05-15T15:23:40.291852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.978498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T15:23:40.29191Z","caller":"traceutil/trace.go:171","msg":"trace[895291625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"216.332867ms","start":"2026-05-15T15:23:40.075554Z","end":"2026-05-15T15:23:40.291887Z","steps":["trace[895291625] 'agreement among raft nodes before linearized reading' (duration: 216.052131ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.291845Z","caller":"traceutil/trace.go:171","msg":"trace[2038847508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"233.524294ms","start":"2026-05-15T15:23:40.058308Z","end":"2026-05-15T15:23:40.291832Z","steps":["trace[2038847508] 'agreement among raft nodes before linearized reading' (duration: 233.243757ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.29191Z","caller":"traceutil/trace.go:171","msg":"trace[1371414450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"220.821567ms","start":"2026-05-15T15:23:40.071073Z","end":"2026-05-15T15:23:40.291895Z","steps":["trace[1371414450] 'agreement among raft nodes before linearized reading' (duration: 220.683113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:40.29199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.822456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T15:23:40.292024Z","caller":"traceutil/trace.go:171","msg":"trace[2053878682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"234.856817ms","start":"2026-05-15T15:23:40.057157Z","end":"2026-05-15T15:23:40.292014Z","steps":["trace[2053878682] 'agreement among raft nodes before linearized reading' (duration: 234.755714ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.291939Z","caller":"traceutil/trace.go:171","msg":"trace[495148501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"218.10154ms","start":"2026-05-15T15:23:40.07382Z","end":"2026-05-15T15:23:40.291921Z","steps":["trace[495148501] 'agreement among raft nodes before linearized reading' (duration: 217.829124ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.74295Z","caller":"traceutil/trace.go:171","msg":"trace[481902914] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"445.481277ms","start":"2026-05-15T15:23:40.297432Z","end":"2026-05-15T15:23:40.742913Z","steps":["trace[481902914] 'process raft request' (duration: 427.279586ms)","trace[481902914] 'compare' (duration: 17.605146ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:40.743014Z","caller":"traceutil/trace.go:171","msg":"trace[80992601] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:771; }","duration":"360.581511ms","start":"2026-05-15T15:23:40.382403Z","end":"2026-05-15T15:23:40.742985Z","steps":["trace[80992601] 'read index received' (duration: 342.128244ms)","trace[80992601] 'applied index is now lower than readState.Index' (duration: 18.451637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:23:40.743126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:40.297404Z","time spent":"445.637601ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:40.743171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.755145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:40.743228Z","caller":"traceutil/trace.go:171","msg":"trace[1245391300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"360.828087ms","start":"2026-05-15T15:23:40.382385Z","end":"2026-05-15T15:23:40.743214Z","steps":["trace[1245391300] 'agreement among raft nodes before linearized reading' (duration: 360.702694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:40.743275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:40.382327Z","time spent":"360.931819ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:23:40.743288Z","caller":"traceutil/trace.go:171","msg":"trace[2067198131] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"445.58852ms","start":"2026-05-15T15:23:40.297667Z","end":"2026-05-15T15:23:40.743256Z","steps":["trace[2067198131] 'process raft request' (duration: 445.1787ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:40.743319Z","caller":"traceutil/trace.go:171","msg":"trace[1435710987] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"445.055667ms","start":"2026-05-15T15:23:40.298244Z","end":"2026-05-15T15:23:40.743299Z","steps":["trace[1435710987] 'process raft request' (duration: 444.686148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:40.743521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:40.297643Z","time spent":"445.716862ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:40.743718Z","caller":"traceutil/trace.go:171","msg":"trace[1771691073] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"446.171084ms","start":"2026-05-15T15:23:40.297529Z","end":"2026-05-15T15:23:40.7437Z","steps":["trace[1771691073] 'process raft request' (duration: 445.218121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:40.743843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:40.297523Z","time spent":"446.242265ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:23:40.743647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:40.298222Z","time spent":"445.144139ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:23:42.893591Z","caller":"traceutil/trace.go:171","msg":"trace[1300261895] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"141.63094ms","start":"2026-05-15T15:23:42.751938Z","end":"2026-05-15T15:23:42.893569Z","steps":["trace[1300261895] 'process raft request' (duration: 141.490526ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:42.894638Z","caller":"traceutil/trace.go:171","msg":"trace[1462843539] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"141.828324ms","start":"2026-05-15T15:23:42.75279Z","end":"2026-05-15T15:23:42.894618Z","steps":["trace[1462843539] 'process raft request' (duration: 141.749942ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:42.894872Z","caller":"traceutil/trace.go:171","msg":"trace[2078597343] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"142.229564ms","start":"2026-05-15T15:23:42.752617Z","end":"2026-05-15T15:23:42.894847Z","steps":["trace[2078597343] 'process raft request' (duration: 141.888646ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:42.895638Z","caller":"traceutil/trace.go:171","msg":"trace[649202955] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"142.512701ms","start":"2026-05-15T15:23:42.752283Z","end":"2026-05-15T15:23:42.894795Z","steps":["trace[649202955] 'process raft request' (duration: 142.102771ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:23:46.552542Z","caller":"traceutil/trace.go:171","msg":"trace[1320644279] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"203.040416ms","start":"2026-05-15T15:23:46.349479Z","end":"2026-05-15T15:23:46.55252Z","steps":["trace[1320644279] 'read index received' (duration: 202.917493ms)","trace[1320644279] 'applied index is now lower than readState.Index' (duration: 121.783µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:23:46.552674Z","caller":"traceutil/trace.go:171","msg":"trace[907405744] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"488.078117ms","start":"2026-05-15T15:23:46.06457Z","end":"2026-05-15T15:23:46.552649Z","steps":["trace[907405744] 'process raft request' (duration: 487.79994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:46.552742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.683354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:23:46.552836Z","caller":"traceutil/trace.go:171","msg":"trace[698237757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"163.822256ms","start":"2026-05-15T15:23:46.389001Z","end":"2026-05-15T15:23:46.552824Z","steps":["trace[698237757] 'agreement among raft nodes before linearized reading' (duration: 163.678423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:46.552752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.272822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T15:23:46.552939Z","caller":"traceutil/trace.go:171","msg":"trace[360776275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"203.497117ms","start":"2026-05-15T15:23:46.349429Z","end":"2026-05-15T15:23:46.552926Z","steps":["trace[360776275] 'agreement among raft nodes before linearized reading' (duration: 203.20844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:23:46.552931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:23:46.064541Z","time spent":"488.277432ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:19.265476Z","caller":"traceutil/trace.go:171","msg":"trace[133733784] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"122.981668ms","start":"2026-05-15T15:24:19.142473Z","end":"2026-05-15T15:24:19.265454Z","steps":["trace[133733784] 'process raft request' (duration: 116.05245ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:19.266046Z","caller":"traceutil/trace.go:171","msg":"trace[1664373732] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"121.825099ms","start":"2026-05-15T15:24:19.144152Z","end":"2026-05-15T15:24:19.265977Z","steps":["trace[1664373732] 'process raft request' (duration: 121.210914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:22.571288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090136284s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:24:22.571686Z","caller":"traceutil/trace.go:171","msg":"trace[2061785196] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.299080474s","start":"2026-05-15T15:24:21.272579Z","end":"2026-05-15T15:24:22.57166Z","steps":["trace[2061785196] 'process raft request' (duration: 208.15154ms)","trace[2061785196] 'compare' (duration: 1.08994255s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:24:22.571809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.27257Z","time spent":"1.299175246s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:23.210856Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.730096761s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-15T15:24:23.383701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000480317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-15T15:24:23.384146Z","caller":"traceutil/trace.go:171","msg":"trace[860770354] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000586689s","start":"2026-05-15T15:24:21.383174Z","end":"2026-05-15T15:24:23.383761Z","steps":["trace[860770354] 'agreement among raft nodes before linearized reading' (duration: 2.000477627s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.38421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.383123Z","time spent":"2.001077082s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:24:23.455444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.169452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:24:23.456147Z","caller":"traceutil/trace.go:171","msg":"trace[571525815] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"2.183369265s","start":"2026-05-15T15:24:21.27274Z","end":"2026-05-15T15:24:23.456109Z","steps":["trace[571525815] 'process raft request' (duration: 1.938348872s)","trace[571525815] 'compare' (duration: 243.202979ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:23.456229Z","caller":"traceutil/trace.go:171","msg":"trace[1843838769] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"2.18230325s","start":"2026-05-15T15:24:21.273909Z","end":"2026-05-15T15:24:23.456212Z","steps":["trace[1843838769] 'process raft request' (duration: 2.182051213s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.456319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.273899Z","time spent":"2.182365821s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:23.456341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.272712Z","time spent":"2.183540369s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:23.456406Z","caller":"traceutil/trace.go:171","msg":"trace[1129785590] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1034; }","duration":"2.164401986s","start":"2026-05-15T15:24:21.291924Z","end":"2026-05-15T15:24:23.456326Z","steps":["trace[1129785590] 'read index received' (duration: 188.818032ms)","trace[1129785590] 'applied index is now lower than readState.Index' (duration: 1.975578564s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:24:23.456555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164613161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:24:23.45663Z","caller":"traceutil/trace.go:171","msg":"trace[71970188] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1000; }","duration":"2.164728964s","start":"2026-05-15T15:24:21.291882Z","end":"2026-05-15T15:24:23.456611Z","steps":["trace[71970188] 'agreement among raft nodes before linearized reading' (duration: 2.164577671s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.456693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.29187Z","time spent":"2.164794995s","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:23.456886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164659253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:23.45693Z","caller":"traceutil/trace.go:171","msg":"trace[1769037347] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1001; }","duration":"2.164729374s","start":"2026-05-15T15:24:21.29219Z","end":"2026-05-15T15:24:23.456919Z","steps":["trace[1769037347] 'agreement among raft nodes before linearized reading' (duration: 2.164637841s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.456958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:21.292178Z","time spent":"2.164772265s","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:23.45721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.424344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T15:24:23.45725Z","caller":"traceutil/trace.go:171","msg":"trace[1327465526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"668.491226ms","start":"2026-05-15T15:24:22.78875Z","end":"2026-05-15T15:24:23.457241Z","steps":["trace[1327465526] 'agreement among raft nodes before linearized reading' (duration: 668.397244ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:23.457266Z","caller":"traceutil/trace.go:171","msg":"trace[460580020] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"843.605227ms","start":"2026-05-15T15:24:22.613641Z","end":"2026-05-15T15:24:23.457246Z","steps":["trace[460580020] 'process raft request' (duration: 842.537571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.457346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121014894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-15T15:24:23.457402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:22.613618Z","time spent":"843.701298ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:23.45762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374136311s","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":"info","ts":"2026-05-15T15:24:23.457662Z","caller":"traceutil/trace.go:171","msg":"trace[1518888774] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1001; }","duration":"1.374349216s","start":"2026-05-15T15:24:22.083301Z","end":"2026-05-15T15:24:23.45765Z","steps":["trace[1518888774] 'agreement among raft nodes before linearized reading' (duration: 1.374155562s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.457713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:22.083283Z","time spent":"1.374419128s","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:23.457843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.531122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:24:23.457905Z","caller":"traceutil/trace.go:171","msg":"trace[1893827153] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1001; }","duration":"218.633154ms","start":"2026-05-15T15:24:23.239256Z","end":"2026-05-15T15:24:23.457889Z","steps":["trace[1893827153] 'agreement among raft nodes before linearized reading' (duration: 218.543962ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:23.457439Z","caller":"traceutil/trace.go:171","msg":"trace[1777972385] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1001; }","duration":"1.121086685s","start":"2026-05-15T15:24:22.336302Z","end":"2026-05-15T15:24:23.457389Z","steps":["trace[1777972385] 'agreement among raft nodes before linearized reading' (duration: 1.120993093s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.458035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:22.33629Z","time spent":"1.121736011s","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-15T15:24:23.458204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.17007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T15:24:23.458239Z","caller":"traceutil/trace.go:171","msg":"trace[1065423836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"222.238641ms","start":"2026-05-15T15:24:23.235992Z","end":"2026-05-15T15:24:23.45823Z","steps":["trace[1065423836] 'agreement among raft nodes before linearized reading' (duration: 222.154689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:23.457278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:22.788734Z","time spent":"668.536417ms","remote":"127.0.0.1:58538","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-05-15T15:24:23.457204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.151799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:24:23.458493Z","caller":"traceutil/trace.go:171","msg":"trace[503544913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"299.449661ms","start":"2026-05-15T15:24:23.159004Z","end":"2026-05-15T15:24:23.458453Z","steps":["trace[503544913] 'agreement among raft nodes before linearized reading' (duration: 298.075818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.283401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:24:46.784386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:24:46.891397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.263879883s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T15:24:46.891907Z","caller":"traceutil/trace.go:171","msg":"trace[1371418328] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"1.264466367s","start":"2026-05-15T15:24:45.627415Z","end":"2026-05-15T15:24:46.891881Z","steps":["trace[1371418328] 'process raft request' (duration: 1.264294793s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.892101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:45.627403Z","time spent":"1.264607011s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:46.914552Z","caller":"traceutil/trace.go:171","msg":"trace[185800287] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1115; }","duration":"1.131929239s","start":"2026-05-15T15:24:45.7826Z","end":"2026-05-15T15:24:46.91453Z","steps":["trace[185800287] 'read index received' (duration: 1.109043609s)","trace[185800287] 'applied index is now lower than readState.Index' (duration: 22.88472ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:46.914675Z","caller":"traceutil/trace.go:171","msg":"trace[1191673215] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"261.620862ms","start":"2026-05-15T15:24:46.653029Z","end":"2026-05-15T15:24:46.91465Z","steps":["trace[1191673215] 'process raft request' (duration: 261.462459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.914779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132159595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-15T15:24:46.91481Z","caller":"traceutil/trace.go:171","msg":"trace[2006543111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.132249797s","start":"2026-05-15T15:24:45.782551Z","end":"2026-05-15T15:24:46.914801Z","steps":["trace[2006543111] 'agreement among raft nodes before linearized reading' (duration: 1.132098883s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.914867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:45.782529Z","time spent":"1.132322778s","remote":"127.0.0.1:58538","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-05-15T15:24:46.914894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.518348ms","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-05-15T15:24:46.914959Z","caller":"traceutil/trace.go:171","msg":"trace[1890258700] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1076; }","duration":"274.61825ms","start":"2026-05-15T15:24:46.640302Z","end":"2026-05-15T15:24:46.91492Z","steps":["trace[1890258700] 'agreement among raft nodes before linearized reading' (duration: 274.533258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.403037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T15:24:46.915148Z","caller":"traceutil/trace.go:171","msg":"trace[1173826424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"998.471788ms","start":"2026-05-15T15:24:45.91667Z","end":"2026-05-15T15:24:46.915142Z","steps":["trace[1173826424] 'agreement among raft nodes before linearized reading' (duration: 998.356946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:45.916649Z","time spent":"998.512159ms","remote":"127.0.0.1:58538","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-05-15T15:24:46.914807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.193335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:46.915209Z","caller":"traceutil/trace.go:171","msg":"trace[639878098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"532.636805ms","start":"2026-05-15T15:24:46.38256Z","end":"2026-05-15T15:24:46.915197Z","steps":["trace[639878098] 'agreement among raft nodes before linearized reading' (duration: 532.170643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:46.382544Z","time spent":"532.698587ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:24:46.915352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002290872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T15:24:46.915374Z","caller":"traceutil/trace.go:171","msg":"trace[743090647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.002338033s","start":"2026-05-15T15:24:45.91303Z","end":"2026-05-15T15:24:46.915368Z","steps":["trace[743090647] 'agreement among raft nodes before linearized reading' (duration: 1.002286912s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.91539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:45.913013Z","time spent":"1.002374133s","remote":"127.0.0.1:58538","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-05-15T15:24:46.915259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.589623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:46.915431Z","caller":"traceutil/trace.go:171","msg":"trace[1305854373] 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:1076; }","duration":"552.793508ms","start":"2026-05-15T15:24:46.362627Z","end":"2026-05-15T15:24:46.91542Z","steps":["trace[1305854373] 'agreement among raft nodes before linearized reading' (duration: 552.613824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:46.362608Z","time spent":"552.87393ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:46.915383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131518589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-15T15:24:46.915453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.257854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:46.915645Z","caller":"traceutil/trace.go:171","msg":"trace[1220572535] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1076; }","duration":"385.51405ms","start":"2026-05-15T15:24:46.53012Z","end":"2026-05-15T15:24:46.915634Z","steps":["trace[1220572535] 'agreement among raft nodes before linearized reading' (duration: 385.275644ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:46.915648Z","caller":"traceutil/trace.go:171","msg":"trace[1091506896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"1.131786726s","start":"2026-05-15T15:24:45.78378Z","end":"2026-05-15T15:24:46.915567Z","steps":["trace[1091506896] 'agreement among raft nodes before linearized reading' (duration: 1.130932445s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:46.5301Z","time spent":"385.613372ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-15T15:24:46.915808Z","caller":"traceutil/trace.go:171","msg":"trace[1277362376] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"787.073433ms","start":"2026-05-15T15:24:46.127579Z","end":"2026-05-15T15:24:46.914653Z","steps":["trace[1277362376] 'process raft request' (duration: 786.768925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:46.915956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:46.127551Z","time spent":"788.355884ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:46.915808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:45.783763Z","time spent":"1.132018911s","remote":"127.0.0.1:58538","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":"info","ts":"2026-05-15T15:24:47.138363Z","caller":"traceutil/trace.go:171","msg":"trace[1560139391] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"217.760401ms","start":"2026-05-15T15:24:46.920572Z","end":"2026-05-15T15:24:47.138332Z","steps":["trace[1560139391] 'process raft request' (duration: 149.106544ms)","trace[1560139391] 'compare' (duration: 68.504374ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:47.14255Z","caller":"traceutil/trace.go:171","msg":"trace[172490726] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"220.853787ms","start":"2026-05-15T15:24:46.921677Z","end":"2026-05-15T15:24:47.14253Z","steps":["trace[172490726] 'process raft request' (duration: 220.673512ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:47.14261Z","caller":"traceutil/trace.go:171","msg":"trace[1778280174] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"221.168274ms","start":"2026-05-15T15:24:46.921419Z","end":"2026-05-15T15:24:47.142587Z","steps":["trace[1778280174] 'process raft request' (duration: 220.720223ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:47.142993Z","caller":"traceutil/trace.go:171","msg":"trace[1075200373] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"220.765204ms","start":"2026-05-15T15:24:46.921622Z","end":"2026-05-15T15:24:47.142387Z","steps":["trace[1075200373] 'process raft request' (duration: 220.665042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:47.738343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.168978ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T15:24:47.738518Z","caller":"traceutil/trace.go:171","msg":"trace[551351219] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"299.96858ms","start":"2026-05-15T15:24:47.438525Z","end":"2026-05-15T15:24:47.738493Z","steps":["trace[551351219] 'read index received' (duration: 50.681µs)","trace[551351219] 'applied index is now lower than readState.Index' (duration: 299.915859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:24:47.73862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.079523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:24:47.738665Z","caller":"traceutil/trace.go:171","msg":"trace[1559923124] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1080; }","duration":"300.232576ms","start":"2026-05-15T15:24:47.43842Z","end":"2026-05-15T15:24:47.738653Z","steps":["trace[1559923124] 'agreement among raft nodes before linearized reading' (duration: 300.134874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:47.738719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:47.438401Z","time spent":"300.302508ms","remote":"127.0.0.1:58540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:49.643785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:24:49.819207Z","caller":"traceutil/trace.go:171","msg":"trace[926517762] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"918.909124ms","start":"2026-05-15T15:24:48.90027Z","end":"2026-05-15T15:24:49.819179Z","steps":["trace[926517762] 'process raft request' (duration: 918.738189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:49.819359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:48.900234Z","time spent":"919.053486ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:50.011017Z","caller":"traceutil/trace.go:171","msg":"trace[1635698393] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"868.112172ms","start":"2026-05-15T15:24:49.14288Z","end":"2026-05-15T15:24:50.010992Z","steps":["trace[1635698393] 'read index received' (duration: 676.027208ms)","trace[1635698393] 'applied index is now lower than readState.Index' (duration: 192.083754ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:50.011046Z","caller":"traceutil/trace.go:171","msg":"trace[426015734] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"1.088155309s","start":"2026-05-15T15:24:48.922869Z","end":"2026-05-15T15:24:50.011025Z","steps":["trace[426015734] 'process raft request' (duration: 1.064969632s)","trace[426015734] 'compare' (duration: 22.932731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:24:50.011166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:48.922837Z","time spent":"1.088257682s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:50.011274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.77516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-15T15:24:50.011312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.406319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T15:24:50.011338Z","caller":"traceutil/trace.go:171","msg":"trace[496999866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"864.890404ms","start":"2026-05-15T15:24:49.146431Z","end":"2026-05-15T15:24:50.011322Z","steps":["trace[496999866] 'agreement among raft nodes before linearized reading' (duration: 864.70617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.011384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:49.146401Z","time spent":"864.971595ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-15T15:24:50.011388Z","caller":"traceutil/trace.go:171","msg":"trace[1322150073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"868.568534ms","start":"2026-05-15T15:24:49.14279Z","end":"2026-05-15T15:24:50.011359Z","steps":["trace[1322150073] 'agreement among raft nodes before linearized reading' (duration: 868.322948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.011422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:49.142765Z","time spent":"868.649085ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:24:50.011484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.834786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:50.011518Z","caller":"traceutil/trace.go:171","msg":"trace[953758392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"628.905158ms","start":"2026-05-15T15:24:49.382603Z","end":"2026-05-15T15:24:50.011508Z","steps":["trace[953758392] 'agreement among raft nodes before linearized reading' (duration: 628.851856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.011545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.742555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-15T15:24:50.011544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:49.382582Z","time spent":"628.956808ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:24:50.011568Z","caller":"traceutil/trace.go:171","msg":"trace[48884137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"863.783227ms","start":"2026-05-15T15:24:49.147778Z","end":"2026-05-15T15:24:50.011561Z","steps":["trace[48884137] 'agreement among raft nodes before linearized reading' (duration: 863.720675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.011603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:49.147769Z","time spent":"863.828988ms","remote":"127.0.0.1:58538","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-05-15T15:24:50.011732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.994346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T15:24:50.01181Z","caller":"traceutil/trace.go:171","msg":"trace[1157384672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"865.105708ms","start":"2026-05-15T15:24:49.146681Z","end":"2026-05-15T15:24:50.011787Z","steps":["trace[1157384672] 'agreement among raft nodes before linearized reading' (duration: 864.855602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.011866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:49.146668Z","time spent":"865.185351ms","remote":"127.0.0.1:58538","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-05-15T15:24:50.218789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.506587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:24:50.218979Z","caller":"traceutil/trace.go:171","msg":"trace[1109156342] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1125; }","duration":"198.641144ms","start":"2026-05-15T15:24:50.020325Z","end":"2026-05-15T15:24:50.218966Z","steps":["trace[1109156342] 'read index received' (duration: 3.103996ms)","trace[1109156342] 'applied index is now lower than readState.Index' (duration: 195.536208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:24:50.21909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.766287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:50.21907Z","caller":"traceutil/trace.go:171","msg":"trace[1436561519] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"202.598301ms","start":"2026-05-15T15:24:50.016447Z","end":"2026-05-15T15:24:50.219045Z","steps":["trace[1436561519] 'process raft request' (duration: 202.482328ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:50.219152Z","caller":"traceutil/trace.go:171","msg":"trace[622766583] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"203.103783ms","start":"2026-05-15T15:24:50.016039Z","end":"2026-05-15T15:24:50.219143Z","steps":["trace[622766583] 'process raft request' (duration: 95.020692ms)","trace[622766583] 'compare' (duration: 107.016545ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:50.219114Z","caller":"traceutil/trace.go:171","msg":"trace[1763524791] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1086; }","duration":"198.841458ms","start":"2026-05-15T15:24:50.020265Z","end":"2026-05-15T15:24:50.219107Z","steps":["trace[1763524791] 'agreement among raft nodes before linearized reading' (duration: 198.778137ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:50.219326Z","caller":"traceutil/trace.go:171","msg":"trace[1194989635] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"202.904078ms","start":"2026-05-15T15:24:50.016383Z","end":"2026-05-15T15:24:50.219287Z","steps":["trace[1194989635] 'process raft request' (duration: 202.503278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.78011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.104242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:50.780181Z","caller":"traceutil/trace.go:171","msg":"trace[283449073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"397.221825ms","start":"2026-05-15T15:24:50.382943Z","end":"2026-05-15T15:24:50.780164Z","steps":["trace[283449073] 'range keys from in-memory index tree' (duration: 396.927877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:50.780217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:50.382922Z","time spent":"397.287767ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:24:52.276213Z","caller":"traceutil/trace.go:171","msg":"trace[109676837] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"249.110407ms","start":"2026-05-15T15:24:52.027078Z","end":"2026-05-15T15:24:52.276188Z","steps":["trace[109676837] 'read index received' (duration: 248.877181ms)","trace[109676837] 'applied index is now lower than readState.Index' (duration: 232.326µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:52.276324Z","caller":"traceutil/trace.go:171","msg":"trace[1864519827] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"259.02167ms","start":"2026-05-15T15:24:52.017273Z","end":"2026-05-15T15:24:52.276294Z","steps":["trace[1864519827] 'process raft request' (duration: 258.737153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:52.276741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.63226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:24:52.276912Z","caller":"traceutil/trace.go:171","msg":"trace[195177929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"249.777833ms","start":"2026-05-15T15:24:52.027037Z","end":"2026-05-15T15:24:52.276815Z","steps":["trace[195177929] 'agreement among raft nodes before linearized reading' (duration: 249.298332ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:52.554304Z","caller":"traceutil/trace.go:171","msg":"trace[491974076] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"274.273032ms","start":"2026-05-15T15:24:52.280002Z","end":"2026-05-15T15:24:52.554275Z","steps":["trace[491974076] 'process raft request' (duration: 252.730936ms)","trace[491974076] 'compare' (duration: 21.412053ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:53.121921Z","caller":"traceutil/trace.go:171","msg":"trace[143699405] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1131; }","duration":"783.572556ms","start":"2026-05-15T15:24:52.338326Z","end":"2026-05-15T15:24:53.121898Z","steps":["trace[143699405] 'read index received' (duration: 194.43067ms)","trace[143699405] 'applied index is now lower than readState.Index' (duration: 589.140706ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:24:53.121983Z","caller":"traceutil/trace.go:171","msg":"trace[1807975005] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"841.437911ms","start":"2026-05-15T15:24:52.280528Z","end":"2026-05-15T15:24:53.121966Z","steps":["trace[1807975005] 'process raft request' (duration: 841.327748ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:24:53.122Z","caller":"traceutil/trace.go:171","msg":"trace[1698793352] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"841.544253ms","start":"2026-05-15T15:24:52.280424Z","end":"2026-05-15T15:24:53.121968Z","steps":["trace[1698793352] 'process raft request' (duration: 841.378109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:53.122128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:52.280518Z","time spent":"841.532533ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:24:53.122178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.830303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-15T15:24:53.122222Z","caller":"traceutil/trace.go:171","msg":"trace[1137856272] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1092; }","duration":"783.926355ms","start":"2026-05-15T15:24:52.338285Z","end":"2026-05-15T15:24:53.122212Z","steps":["trace[1137856272] 'agreement among raft nodes before linearized reading' (duration: 783.75285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:53.122269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:52.338267Z","time spent":"783.986677ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-15T15:24:53.122169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:52.280324Z","time spent":"841.747068ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:53.122481Z","caller":"traceutil/trace.go:171","msg":"trace[20631737] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"841.557833ms","start":"2026-05-15T15:24:52.280354Z","end":"2026-05-15T15:24:53.121912Z","steps":["trace[20631737] 'process raft request' (duration: 841.271546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:53.122594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.691298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:24:53.122596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:52.280336Z","time spent":"842.21095ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:24:53.12264Z","caller":"traceutil/trace.go:171","msg":"trace[119179645] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1092; }","duration":"503.774ms","start":"2026-05-15T15:24:52.618851Z","end":"2026-05-15T15:24:53.122625Z","steps":["trace[119179645] 'agreement among raft nodes before linearized reading' (duration: 503.708468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:24:53.122671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:24:52.618832Z","time spent":"503.830961ms","remote":"127.0.0.1:58506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:24:53.659021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.387624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:24:53.659135Z","caller":"traceutil/trace.go:171","msg":"trace[1064426700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"276.542598ms","start":"2026-05-15T15:24:53.38256Z","end":"2026-05-15T15:24:53.659103Z","steps":["trace[1064426700] 'range keys from in-memory index tree' (duration: 276.313972ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:04.295195Z","caller":"traceutil/trace.go:171","msg":"trace[2050849120] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"384.626988ms","start":"2026-05-15T15:25:03.910541Z","end":"2026-05-15T15:25:04.295168Z","steps":["trace[2050849120] 'process raft request' (duration: 384.432694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:04.295354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:03.910524Z","time spent":"384.752861ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:04.378503Z","caller":"traceutil/trace.go:171","msg":"trace[1481185576] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"171.911801ms","start":"2026-05-15T15:25:04.206573Z","end":"2026-05-15T15:25:04.378485Z","steps":["trace[1481185576] 'process raft request' (duration: 168.030046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:05.707473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809701,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:25:06.11161Z","caller":"traceutil/trace.go:171","msg":"trace[1849703238] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"905.109747ms","start":"2026-05-15T15:25:05.206481Z","end":"2026-05-15T15:25:06.111591Z","steps":["trace[1849703238] 'read index received' (duration: 904.965903ms)","trace[1849703238] 'applied index is now lower than readState.Index' (duration: 143.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:06.111693Z","caller":"traceutil/trace.go:171","msg":"trace[1581915169] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"935.988481ms","start":"2026-05-15T15:25:05.175698Z","end":"2026-05-15T15:25:06.111686Z","steps":["trace[1581915169] 'process raft request' (duration: 935.790416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:06.111764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:05.175676Z","time spent":"936.035432ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:06.111919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.441695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T15:25:06.111947Z","caller":"traceutil/trace.go:171","msg":"trace[232950413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"905.492105ms","start":"2026-05-15T15:25:05.206447Z","end":"2026-05-15T15:25:06.111939Z","steps":["trace[232950413] 'agreement among raft nodes before linearized reading' (duration: 905.405333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:06.111966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:05.206423Z","time spent":"905.538217ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T15:25:06.112054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.09116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-15T15:25:06.112076Z","caller":"traceutil/trace.go:171","msg":"trace[189363040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"897.148342ms","start":"2026-05-15T15:25:05.214922Z","end":"2026-05-15T15:25:06.11207Z","steps":["trace[189363040] 'agreement among raft nodes before linearized reading' (duration: 897.0959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:06.112092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:05.2149Z","time spent":"897.188423ms","remote":"127.0.0.1:58538","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-05-15T15:25:06.112239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.059341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:25:06.112324Z","caller":"traceutil/trace.go:171","msg":"trace[1108638839] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1183; }","duration":"293.191084ms","start":"2026-05-15T15:25:05.819114Z","end":"2026-05-15T15:25:06.112305Z","steps":["trace[1108638839] 'agreement among raft nodes before linearized reading' (duration: 292.989839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:06.80101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809704,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:25:07.054089Z","caller":"traceutil/trace.go:171","msg":"trace[2020406435] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"938.192435ms","start":"2026-05-15T15:25:06.115865Z","end":"2026-05-15T15:25:07.054057Z","steps":["trace[2020406435] 'process raft request' (duration: 937.281633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.054246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.115846Z","time spent":"938.326008ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:07.086144Z","caller":"traceutil/trace.go:171","msg":"trace[765258739] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1231; }","duration":"786.050487ms","start":"2026-05-15T15:25:06.300071Z","end":"2026-05-15T15:25:07.086122Z","steps":["trace[765258739] 'read index received' (duration: 753.063161ms)","trace[765258739] 'applied index is now lower than readState.Index' (duration: 32.986216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:25:07.086424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.334374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-15T15:25:07.086494Z","caller":"traceutil/trace.go:171","msg":"trace[1669461740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"786.452487ms","start":"2026-05-15T15:25:06.300026Z","end":"2026-05-15T15:25:07.086479Z","steps":["trace[1669461740] 'agreement among raft nodes before linearized reading' (duration: 786.232022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.086512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.546576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:25:07.08656Z","caller":"traceutil/trace.go:171","msg":"trace[1482944288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"704.603267ms","start":"2026-05-15T15:25:06.381944Z","end":"2026-05-15T15:25:07.086547Z","steps":["trace[1482944288] 'agreement among raft nodes before linearized reading' (duration: 704.527165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.086575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.766478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:25:07.086596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.381873Z","time spent":"704.71697ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:25:07.086253Z","caller":"traceutil/trace.go:171","msg":"trace[1531811466] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"698.434517ms","start":"2026-05-15T15:25:06.387784Z","end":"2026-05-15T15:25:07.086218Z","steps":["trace[1531811466] 'process raft request' (duration: 698.296733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.086685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.387766Z","time spent":"698.873157ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:07.086527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.300002Z","time spent":"786.518988ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T15:25:07.086597Z","caller":"traceutil/trace.go:171","msg":"trace[782081387] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1186; }","duration":"311.819499ms","start":"2026-05-15T15:25:06.77477Z","end":"2026-05-15T15:25:07.08659Z","steps":["trace[782081387] 'agreement among raft nodes before linearized reading' (duration: 311.778548ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:07.086324Z","caller":"traceutil/trace.go:171","msg":"trace[873847264] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"969.829458ms","start":"2026-05-15T15:25:06.116483Z","end":"2026-05-15T15:25:07.086312Z","steps":["trace[873847264] 'process raft request' (duration: 969.47688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.086829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.11646Z","time spent":"970.35159ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:07.086854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.774756Z","time spent":"312.052685ms","remote":"127.0.0.1:58518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:25:07.08687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.117216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T15:25:07.086933Z","caller":"traceutil/trace.go:171","msg":"trace[1673736827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1186; }","duration":"472.207999ms","start":"2026-05-15T15:25:06.61471Z","end":"2026-05-15T15:25:07.086918Z","steps":["trace[1673736827] 'agreement among raft nodes before linearized reading' (duration: 472.010024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:07.086989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:06.614697Z","time spent":"472.2799ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:25:23.650553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:25:23.700172Z","caller":"traceutil/trace.go:171","msg":"trace[901393316] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"561.544632ms","start":"2026-05-15T15:25:23.138595Z","end":"2026-05-15T15:25:23.700139Z","steps":["trace[901393316] 'process raft request' (duration: 561.370897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.700377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.138584Z","time spent":"561.674755ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:23.770799Z","caller":"traceutil/trace.go:171","msg":"trace[1636943139] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"620.39582ms","start":"2026-05-15T15:25:23.150384Z","end":"2026-05-15T15:25:23.770779Z","steps":["trace[1636943139] 'read index received' (duration: 549.580379ms)","trace[1636943139] 'applied index is now lower than readState.Index' (duration: 70.814531ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:23.770901Z","caller":"traceutil/trace.go:171","msg":"trace[1303545494] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"624.776227ms","start":"2026-05-15T15:25:23.146097Z","end":"2026-05-15T15:25:23.770873Z","steps":["trace[1303545494] 'process raft request' (duration: 624.560322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.771009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.974095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-15T15:25:23.771039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.146081Z","time spent":"624.89243ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:23.77105Z","caller":"traceutil/trace.go:171","msg":"trace[852470943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"619.044857ms","start":"2026-05-15T15:25:23.151994Z","end":"2026-05-15T15:25:23.771039Z","steps":["trace[852470943] 'agreement among raft nodes before linearized reading' (duration: 618.913744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.771077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.151988Z","time spent":"619.081828ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T15:25:23.771078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.669007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-15T15:25:23.771152Z","caller":"traceutil/trace.go:171","msg":"trace[1970675256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"620.774699ms","start":"2026-05-15T15:25:23.15036Z","end":"2026-05-15T15:25:23.771134Z","steps":["trace[1970675256] 'agreement among raft nodes before linearized reading' (duration: 620.548453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.771142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.229242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:25:23.771196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.150351Z","time spent":"620.838061ms","remote":"127.0.0.1:58538","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-05-15T15:25:23.771231Z","caller":"traceutil/trace.go:171","msg":"trace[351596688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"387.358705ms","start":"2026-05-15T15:25:23.383851Z","end":"2026-05-15T15:25:23.771209Z","steps":["trace[351596688] 'agreement among raft nodes before linearized reading' (duration: 387.227902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.771259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.177382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:25:23.77128Z","caller":"traceutil/trace.go:171","msg":"trace[1424177788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"617.218622ms","start":"2026-05-15T15:25:23.154056Z","end":"2026-05-15T15:25:23.771274Z","steps":["trace[1424177788] 'agreement among raft nodes before linearized reading' (duration: 617.11576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:23.771283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.383831Z","time spent":"387.442618ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:25:23.771302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:23.154047Z","time spent":"617.250733ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:25:24.035602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.837571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:25:24.035752Z","caller":"traceutil/trace.go:171","msg":"trace[2079998242] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"252.182482ms","start":"2026-05-15T15:25:23.783555Z","end":"2026-05-15T15:25:24.035738Z","steps":["trace[2079998242] 'process raft request' (duration: 252.155371ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:24.035921Z","caller":"traceutil/trace.go:171","msg":"trace[362555206] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"260.956836ms","start":"2026-05-15T15:25:23.77495Z","end":"2026-05-15T15:25:24.035907Z","steps":["trace[362555206] 'process raft request' (duration: 153.741626ms)","trace[362555206] 'compare' (duration: 106.765499ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:24.035944Z","caller":"traceutil/trace.go:171","msg":"trace[2063456567] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"258.286011ms","start":"2026-05-15T15:25:23.777641Z","end":"2026-05-15T15:25:24.035927Z","steps":["trace[2063456567] 'process raft request' (duration: 258.034174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:25.885751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037809884,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:25:26.007346Z","caller":"traceutil/trace.go:171","msg":"trace[698771526] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"991.436905ms","start":"2026-05-15T15:25:25.015871Z","end":"2026-05-15T15:25:26.007308Z","steps":["trace[698771526] 'process raft request' (duration: 991.268461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:26.007547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:25.015849Z","time spent":"991.6056ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:26.407088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.426965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:25:26.407186Z","caller":"traceutil/trace.go:171","msg":"trace[393707877] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1314; }","duration":"1.02188526s","start":"2026-05-15T15:25:25.385284Z","end":"2026-05-15T15:25:26.407169Z","steps":["trace[393707877] 'read index received' (duration: 621.878516ms)","trace[393707877] 'applied index is now lower than readState.Index' (duration: 400.005534ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:26.407284Z","caller":"traceutil/trace.go:171","msg":"trace[1667482341] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"630.489916ms","start":"2026-05-15T15:25:25.776783Z","end":"2026-05-15T15:25:26.407272Z","steps":["trace[1667482341] 'process raft request' (duration: 407.803025ms)","trace[1667482341] 'compare' (duration: 222.25518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:25:26.407355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:25.776765Z","time spent":"630.551218ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:26.407379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022092225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:25:26.407436Z","caller":"traceutil/trace.go:171","msg":"trace[1721653831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"1.022189077s","start":"2026-05-15T15:25:25.385233Z","end":"2026-05-15T15:25:26.407422Z","steps":["trace[1721653831] 'agreement among raft nodes before linearized reading' (duration: 1.022099445s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:26.407466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:25.38521Z","time spent":"1.022251339s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:25:26.407499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.939617ms","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-05-15T15:25:26.407528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.888235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:25:26.407579Z","caller":"traceutil/trace.go:171","msg":"trace[2013912583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"703.127571ms","start":"2026-05-15T15:25:25.704429Z","end":"2026-05-15T15:25:26.407556Z","steps":["trace[2013912583] 'agreement among raft nodes before linearized reading' (duration: 702.920656ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.407607Z","caller":"traceutil/trace.go:171","msg":"trace[558705540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"366.994567ms","start":"2026-05-15T15:25:26.040594Z","end":"2026-05-15T15:25:26.407588Z","steps":["trace[558705540] 'agreement among raft nodes before linearized reading' (duration: 366.779312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:26.407614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.930451ms","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-05-15T15:25:26.40763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:25.704409Z","time spent":"703.210733ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T15:25:26.407655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:26.040583Z","time spent":"367.063599ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:25:26.407517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.638043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T15:25:26.407662Z","caller":"traceutil/trace.go:171","msg":"trace[247641431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"368.011753ms","start":"2026-05-15T15:25:26.039637Z","end":"2026-05-15T15:25:26.407649Z","steps":["trace[247641431] 'agreement among raft nodes before linearized reading' (duration: 367.917771ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.407696Z","caller":"traceutil/trace.go:171","msg":"trace[1637881141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"367.847179ms","start":"2026-05-15T15:25:26.03984Z","end":"2026-05-15T15:25:26.407688Z","steps":["trace[1637881141] 'agreement among raft nodes before linearized reading' (duration: 367.535241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:26.407699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:26.039618Z","time spent":"368.075254ms","remote":"127.0.0.1:58538","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-05-15T15:25:26.407726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:26.039828Z","time spent":"367.89059ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T15:25:26.407809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.20868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T15:25:26.407846Z","caller":"traceutil/trace.go:171","msg":"trace[315828701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"161.26356ms","start":"2026-05-15T15:25:26.246569Z","end":"2026-05-15T15:25:26.407833Z","steps":["trace[315828701] 'agreement among raft nodes before linearized reading' (duration: 161.180468ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.638555Z","caller":"traceutil/trace.go:171","msg":"trace[1465683370] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"225.987372ms","start":"2026-05-15T15:25:26.412548Z","end":"2026-05-15T15:25:26.638536Z","steps":["trace[1465683370] 'process raft request' (duration: 225.745026ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.638609Z","caller":"traceutil/trace.go:171","msg":"trace[496877599] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"225.299774ms","start":"2026-05-15T15:25:26.413303Z","end":"2026-05-15T15:25:26.638603Z","steps":["trace[496877599] 'process raft request' (duration: 225.210752ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.638595Z","caller":"traceutil/trace.go:171","msg":"trace[1534011773] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"225.393006ms","start":"2026-05-15T15:25:26.413169Z","end":"2026-05-15T15:25:26.638562Z","steps":["trace[1534011773] 'process raft request' (duration: 225.282703ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:26.638555Z","caller":"traceutil/trace.go:171","msg":"trace[1815925212] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"225.255974ms","start":"2026-05-15T15:25:26.413282Z","end":"2026-05-15T15:25:26.638538Z","steps":["trace[1815925212] 'process raft request' (duration: 225.201832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:28.340752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.984009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T15:25:28.342053Z","caller":"traceutil/trace.go:171","msg":"trace[839341530] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"316.975586ms","start":"2026-05-15T15:25:28.025052Z","end":"2026-05-15T15:25:28.342027Z","steps":["trace[839341530] 'process raft request' (duration: 316.829352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:28.342357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.025024Z","time spent":"317.096428ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:28.665368Z","caller":"traceutil/trace.go:171","msg":"trace[1145014532] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"190.128296ms","start":"2026-05-15T15:25:28.475214Z","end":"2026-05-15T15:25:28.665342Z","steps":["trace[1145014532] 'read index received' (duration: 189.928211ms)","trace[1145014532] 'applied index is now lower than readState.Index' (duration: 199.075µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:28.665507Z","caller":"traceutil/trace.go:171","msg":"trace[377041680] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"253.458374ms","start":"2026-05-15T15:25:28.412038Z","end":"2026-05-15T15:25:28.665497Z","steps":["trace[377041680] 'process raft request' (duration: 253.170167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:28.665679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.458564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:25:28.665702Z","caller":"traceutil/trace.go:171","msg":"trace[146081884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"190.516576ms","start":"2026-05-15T15:25:28.475179Z","end":"2026-05-15T15:25:28.665695Z","steps":["trace[146081884] 'agreement among raft nodes before linearized reading' (duration: 190.460994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:29.042171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.184623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:25:29.042328Z","caller":"traceutil/trace.go:171","msg":"trace[1409762621] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"373.940027ms","start":"2026-05-15T15:25:28.668377Z","end":"2026-05-15T15:25:29.042317Z","steps":["trace[1409762621] 'process raft request' (duration: 202.56019ms)","trace[1409762621] 'compare' (duration: 171.040999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:25:29.042363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.668366Z","time spent":"373.980538ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:29.042461Z","caller":"traceutil/trace.go:171","msg":"trace[1258591828] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"373.62494ms","start":"2026-05-15T15:25:28.66883Z","end":"2026-05-15T15:25:29.042455Z","steps":["trace[1258591828] 'process raft request' (duration: 373.411924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:29.042483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.668817Z","time spent":"373.65411ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:29.042831Z","caller":"traceutil/trace.go:171","msg":"trace[771314301] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"373.416114ms","start":"2026-05-15T15:25:28.669398Z","end":"2026-05-15T15:25:29.042814Z","steps":["trace[771314301] 'process raft request' (duration: 373.038985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:29.042909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.669392Z","time spent":"373.473376ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:29.043083Z","caller":"traceutil/trace.go:171","msg":"trace[1481741694] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"371.62225ms","start":"2026-05-15T15:25:28.671436Z","end":"2026-05-15T15:25:29.043059Z","steps":["trace[1481741694] 'process raft request' (duration: 371.543508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:29.04322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.671428Z","time spent":"371.723383ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:29.04332Z","caller":"traceutil/trace.go:171","msg":"trace[337553876] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"373.769083ms","start":"2026-05-15T15:25:28.669539Z","end":"2026-05-15T15:25:29.043308Z","steps":["trace[337553876] 'process raft request' (duration: 373.353503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:29.043375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:28.669517Z","time spent":"373.829274ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:38.185588Z","caller":"traceutil/trace.go:171","msg":"trace[884804331] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"740.420382ms","start":"2026-05-15T15:25:37.445134Z","end":"2026-05-15T15:25:38.185555Z","steps":["trace[884804331] 'process raft request' (duration: 740.177146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:38.18577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:37.445114Z","time spent":"740.564175ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:38.881753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037810041,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:25:39.382772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037810041,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:25:39.393641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204434091s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T15:25:39.394357Z","caller":"traceutil/trace.go:171","msg":"trace[1380227868] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"1.205206129s","start":"2026-05-15T15:25:38.18913Z","end":"2026-05-15T15:25:39.394336Z","steps":["trace[1380227868] 'process raft request' (duration: 1.205061565s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.39448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:38.189109Z","time spent":"1.205303972s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:25:39.655878Z","caller":"traceutil/trace.go:171","msg":"trace[1161996439] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1403; }","duration":"1.274917442s","start":"2026-05-15T15:25:38.38094Z","end":"2026-05-15T15:25:39.655858Z","steps":["trace[1161996439] 'read index received' (duration: 1.013191147s)","trace[1161996439] 'applied index is now lower than readState.Index' (duration: 261.725635ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:25:39.655967Z","caller":"traceutil/trace.go:171","msg":"trace[1339033474] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.15692274s","start":"2026-05-15T15:25:38.498993Z","end":"2026-05-15T15:25:39.655916Z","steps":["trace[1339033474] 'process raft request' (duration: 1.152883341s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:39.655972Z","caller":"traceutil/trace.go:171","msg":"trace[699675853] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"955.634071ms","start":"2026-05-15T15:25:38.700307Z","end":"2026-05-15T15:25:39.655941Z","steps":["trace[699675853] 'process raft request' (duration: 955.491488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.65608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275090236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:25:39.656118Z","caller":"traceutil/trace.go:171","msg":"trace[2093201208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"1.275195209s","start":"2026-05-15T15:25:38.380911Z","end":"2026-05-15T15:25:39.656106Z","steps":["trace[2093201208] 'agreement among raft nodes before linearized reading' (duration: 1.275074496s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.65613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:38.498964Z","time spent":"1.157064112s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:39.656129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:38.700283Z","time spent":"955.759045ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:25:39.656279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.608867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-15T15:25:39.656151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:38.380897Z","time spent":"1.27524372s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:25:39.656355Z","caller":"traceutil/trace.go:171","msg":"trace[1876807837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"544.71986ms","start":"2026-05-15T15:25:39.111627Z","end":"2026-05-15T15:25:39.656347Z","steps":["trace[1876807837] 'agreement among raft nodes before linearized reading' (duration: 544.556336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.656583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.111602Z","time spent":"544.969297ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T15:25:39.656849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.096656ms","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:530"} {"level":"info","ts":"2026-05-15T15:25:39.656874Z","caller":"traceutil/trace.go:171","msg":"trace[1496238239] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1351; }","duration":"418.170788ms","start":"2026-05-15T15:25:39.238697Z","end":"2026-05-15T15:25:39.656867Z","steps":["trace[1496238239] 'agreement among raft nodes before linearized reading' (duration: 418.089286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.656892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.238674Z","time spent":"418.213509ms","remote":"127.0.0.1:58538","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-05-15T15:25:39.656925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.137187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T15:25:39.656967Z","caller":"traceutil/trace.go:171","msg":"trace[22458603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"524.207739ms","start":"2026-05-15T15:25:39.132747Z","end":"2026-05-15T15:25:39.656955Z","steps":["trace[22458603] 'agreement among raft nodes before linearized reading' (duration: 524.080206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.656999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.132734Z","time spent":"524.25753ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T15:25:39.657017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.027859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T15:25:39.657073Z","caller":"traceutil/trace.go:171","msg":"trace[483255226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"516.135312ms","start":"2026-05-15T15:25:39.140925Z","end":"2026-05-15T15:25:39.657061Z","steps":["trace[483255226] 'agreement among raft nodes before linearized reading' (duration: 516.014559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.657103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.140902Z","time spent":"516.195074ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T15:25:39.65712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.023509ms","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-05-15T15:25:39.657022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.003859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:25:39.657157Z","caller":"traceutil/trace.go:171","msg":"trace[667596786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"516.068491ms","start":"2026-05-15T15:25:39.141076Z","end":"2026-05-15T15:25:39.657144Z","steps":["trace[667596786] 'agreement among raft nodes before linearized reading' (duration: 515.979529ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:39.657183Z","caller":"traceutil/trace.go:171","msg":"trace[753708456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"516.189673ms","start":"2026-05-15T15:25:39.140968Z","end":"2026-05-15T15:25:39.657157Z","steps":["trace[753708456] 'agreement among raft nodes before linearized reading' (duration: 515.908136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:25:39.657198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.14107Z","time spent":"516.112732ms","remote":"127.0.0.1:58538","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-05-15T15:25:39.657244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:25:39.140954Z","time spent":"516.272596ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T15:25:43.862602Z","caller":"traceutil/trace.go:171","msg":"trace[639185534] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"143.88791ms","start":"2026-05-15T15:25:43.718689Z","end":"2026-05-15T15:25:43.862576Z","steps":["trace[639185534] 'process raft request' (duration: 143.582912ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:43.863124Z","caller":"traceutil/trace.go:171","msg":"trace[845925588] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"144.348371ms","start":"2026-05-15T15:25:43.718751Z","end":"2026-05-15T15:25:43.863099Z","steps":["trace[845925588] 'process raft request' (duration: 143.689585ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:43.863364Z","caller":"traceutil/trace.go:171","msg":"trace[1049930286] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"144.381522ms","start":"2026-05-15T15:25:43.718969Z","end":"2026-05-15T15:25:43.863351Z","steps":["trace[1049930286] 'process raft request' (duration: 144.088385ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:25:43.863634Z","caller":"traceutil/trace.go:171","msg":"trace[1844106646] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"144.509474ms","start":"2026-05-15T15:25:43.719108Z","end":"2026-05-15T15:25:43.863618Z","steps":["trace[1844106646] 'process raft request' (duration: 144.169986ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:10.220429Z","caller":"traceutil/trace.go:171","msg":"trace[2037501626] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"235.490294ms","start":"2026-05-15T15:26:09.984904Z","end":"2026-05-15T15:26:10.220394Z","steps":["trace[2037501626] 'process raft request' (duration: 181.345794ms)","trace[2037501626] 'compare' (duration: 54.022258ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:10.28235Z","caller":"traceutil/trace.go:171","msg":"trace[1144669313] linearizableReadLoop","detail":"{readStateIndex:1555; appliedIndex:1553; }","duration":"290.916356ms","start":"2026-05-15T15:26:09.991406Z","end":"2026-05-15T15:26:10.282322Z","steps":["trace[1144669313] 'read index received' (duration: 174.857035ms)","trace[1144669313] 'applied index is now lower than readState.Index' (duration: 116.058171ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:10.282484Z","caller":"traceutil/trace.go:171","msg":"trace[1438259237] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"296.375909ms","start":"2026-05-15T15:26:09.986068Z","end":"2026-05-15T15:26:10.282444Z","steps":["trace[1438259237] 'process raft request' (duration: 296.118743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:10.282657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.224143ms","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-05-15T15:26:10.282738Z","caller":"traceutil/trace.go:171","msg":"trace[1540095482] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1494; }","duration":"291.333807ms","start":"2026-05-15T15:26:09.991385Z","end":"2026-05-15T15:26:10.282719Z","steps":["trace[1540095482] 'agreement among raft nodes before linearized reading' (duration: 291.103061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:10.283096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.243401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-15T15:26:10.283347Z","caller":"traceutil/trace.go:171","msg":"trace[991391912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1494; }","duration":"288.473456ms","start":"2026-05-15T15:26:09.994802Z","end":"2026-05-15T15:26:10.283276Z","steps":["trace[991391912] 'agreement among raft nodes before linearized reading' (duration: 288.082127ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:12.065071Z","caller":"traceutil/trace.go:171","msg":"trace[1000753764] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"129.089139ms","start":"2026-05-15T15:26:11.935963Z","end":"2026-05-15T15:26:12.065052Z","steps":["trace[1000753764] 'read index received' (duration: 128.870743ms)","trace[1000753764] 'applied index is now lower than readState.Index' (duration: 217.456µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:12.065154Z","caller":"traceutil/trace.go:171","msg":"trace[1263322629] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"149.696502ms","start":"2026-05-15T15:26:11.915443Z","end":"2026-05-15T15:26:12.065139Z","steps":["trace[1263322629] 'process raft request' (duration: 149.487567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:12.065219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.249033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:26:12.065273Z","caller":"traceutil/trace.go:171","msg":"trace[977837372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1500; }","duration":"129.362116ms","start":"2026-05-15T15:26:11.935898Z","end":"2026-05-15T15:26:12.06526Z","steps":["trace[977837372] 'agreement among raft nodes before linearized reading' (duration: 129.246873ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:24.592394Z","caller":"traceutil/trace.go:171","msg":"trace[69161843] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"235.73436ms","start":"2026-05-15T15:26:24.356621Z","end":"2026-05-15T15:26:24.592356Z","steps":["trace[69161843] 'read index received' (duration: 235.409342ms)","trace[69161843] 'applied index is now lower than readState.Index' (duration: 323.878µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:24.59256Z","caller":"traceutil/trace.go:171","msg":"trace[1546137311] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"238.813795ms","start":"2026-05-15T15:26:24.353693Z","end":"2026-05-15T15:26:24.592507Z","steps":["trace[1546137311] 'process raft request' (duration: 238.504388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:24.592847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.851837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:26:24.59296Z","caller":"traceutil/trace.go:171","msg":"trace[10951355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1557; }","duration":"212.006911ms","start":"2026-05-15T15:26:24.380934Z","end":"2026-05-15T15:26:24.592941Z","steps":["trace[10951355] 'agreement among raft nodes before linearized reading' (duration: 211.856067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:24.593166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.762797ms","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-05-15T15:26:24.593217Z","caller":"traceutil/trace.go:171","msg":"trace[193151388] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1557; }","duration":"134.867559ms","start":"2026-05-15T15:26:24.458338Z","end":"2026-05-15T15:26:24.593206Z","steps":["trace[193151388] 'agreement among raft nodes before linearized reading' (duration: 134.714686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:24.593595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.807876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T15:26:24.593701Z","caller":"traceutil/trace.go:171","msg":"trace[1715561676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1557; }","duration":"237.095313ms","start":"2026-05-15T15:26:24.356582Z","end":"2026-05-15T15:26:24.593677Z","steps":["trace[1715561676] 'agreement among raft nodes before linearized reading' (duration: 236.513839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:24.59417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.166168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-15T15:26:24.59429Z","caller":"traceutil/trace.go:171","msg":"trace[1996791650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1557; }","duration":"183.337992ms","start":"2026-05-15T15:26:24.410921Z","end":"2026-05-15T15:26:24.594259Z","steps":["trace[1996791650] 'agreement among raft nodes before linearized reading' (duration: 182.964573ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:24.766269Z","caller":"traceutil/trace.go:171","msg":"trace[1858485532] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"165.761753ms","start":"2026-05-15T15:26:24.600487Z","end":"2026-05-15T15:26:24.766249Z","steps":["trace[1858485532] 'process raft request' (duration: 165.726222ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:24.766301Z","caller":"traceutil/trace.go:171","msg":"trace[1665056364] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"166.266726ms","start":"2026-05-15T15:26:24.6Z","end":"2026-05-15T15:26:24.766267Z","steps":["trace[1665056364] 'process raft request' (duration: 66.765449ms)","trace[1665056364] 'compare' (duration: 99.192489ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:24.766283Z","caller":"traceutil/trace.go:171","msg":"trace[1290551473] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"165.941878ms","start":"2026-05-15T15:26:24.600325Z","end":"2026-05-15T15:26:24.766267Z","steps":["trace[1290551473] 'process raft request' (duration: 165.844775ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:32.035043Z","caller":"traceutil/trace.go:171","msg":"trace[1123295786] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1654; }","duration":"135.434764ms","start":"2026-05-15T15:26:31.899581Z","end":"2026-05-15T15:26:32.035016Z","steps":["trace[1123295786] 'read index received' (duration: 135.28595ms)","trace[1123295786] 'applied index is now lower than readState.Index' (duration: 147.383µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:32.035229Z","caller":"traceutil/trace.go:171","msg":"trace[992534231] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"136.246173ms","start":"2026-05-15T15:26:31.898971Z","end":"2026-05-15T15:26:32.035217Z","steps":["trace[992534231] 'process raft request' (duration: 135.874624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:32.035882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.277744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-15T15:26:32.035968Z","caller":"traceutil/trace.go:171","msg":"trace[911657125] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1590; }","duration":"136.424777ms","start":"2026-05-15T15:26:31.899525Z","end":"2026-05-15T15:26:32.035949Z","steps":["trace[911657125] 'agreement among raft nodes before linearized reading' (duration: 136.082039ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:32.321786Z","caller":"traceutil/trace.go:171","msg":"trace[971216344] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"101.23144ms","start":"2026-05-15T15:26:32.220517Z","end":"2026-05-15T15:26:32.321749Z","steps":["trace[971216344] 'process raft request' (duration: 100.477461ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:39.079385Z","caller":"traceutil/trace.go:171","msg":"trace[1354175238] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"188.500428ms","start":"2026-05-15T15:26:38.89086Z","end":"2026-05-15T15:26:39.07936Z","steps":["trace[1354175238] 'process raft request' (duration: 188.316643ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:39.15593Z","caller":"traceutil/trace.go:171","msg":"trace[1996547226] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"264.623055ms","start":"2026-05-15T15:26:38.891269Z","end":"2026-05-15T15:26:39.155892Z","steps":["trace[1996547226] 'process raft request' (duration: 225.419938ms)","trace[1996547226] 'compare' (duration: 39.047693ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:42.924849Z","caller":"traceutil/trace.go:171","msg":"trace[2123685712] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"134.094541ms","start":"2026-05-15T15:26:42.790735Z","end":"2026-05-15T15:26:42.924829Z","steps":["trace[2123685712] 'process raft request' (duration: 133.964538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:49.882649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037810826,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:26:49.934878Z","caller":"traceutil/trace.go:171","msg":"trace[1711433208] linearizableReadLoop","detail":"{readStateIndex:1740; appliedIndex:1739; }","duration":"552.817025ms","start":"2026-05-15T15:26:49.38201Z","end":"2026-05-15T15:26:49.934827Z","steps":["trace[1711433208] 'read index received' (duration: 552.659161ms)","trace[1711433208] 'applied index is now lower than readState.Index' (duration: 156.704µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:49.934973Z","caller":"traceutil/trace.go:171","msg":"trace[858860389] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"734.845606ms","start":"2026-05-15T15:26:49.200096Z","end":"2026-05-15T15:26:49.934942Z","steps":["trace[858860389] 'process raft request' (duration: 734.562599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:49.935046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.01789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:26:49.935074Z","caller":"traceutil/trace.go:171","msg":"trace[669859974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1671; }","duration":"553.101732ms","start":"2026-05-15T15:26:49.381966Z","end":"2026-05-15T15:26:49.935067Z","steps":["trace[669859974] 'agreement among raft nodes before linearized reading' (duration: 553.02283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:49.935093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:26:49.381947Z","time spent":"553.142443ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:26:49.935085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.625337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:26:49.935123Z","caller":"traceutil/trace.go:171","msg":"trace[65546740] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1671; }","duration":"275.73212ms","start":"2026-05-15T15:26:49.659381Z","end":"2026-05-15T15:26:49.935114Z","steps":["trace[65546740] 'agreement among raft nodes before linearized reading' (duration: 275.633648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:49.935185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:26:49.200074Z","time spent":"734.950809ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:26:49.935281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.146859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-15T15:26:49.93535Z","caller":"traceutil/trace.go:171","msg":"trace[1863848245] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1671; }","duration":"117.308733ms","start":"2026-05-15T15:26:49.818022Z","end":"2026-05-15T15:26:49.935331Z","steps":["trace[1863848245] 'agreement among raft nodes before linearized reading' (duration: 117.125818ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:57.408377Z","caller":"traceutil/trace.go:171","msg":"trace[675089102] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"197.604154ms","start":"2026-05-15T15:26:57.21075Z","end":"2026-05-15T15:26:57.408354Z","steps":["trace[675089102] 'process raft request' (duration: 197.366728ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:26:57.641256Z","caller":"traceutil/trace.go:171","msg":"trace[322855506] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"430.132096ms","start":"2026-05-15T15:26:57.211078Z","end":"2026-05-15T15:26:57.64121Z","steps":["trace[322855506] 'process raft request' (duration: 356.00368ms)","trace[322855506] 'compare' (duration: 73.949142ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:57.641319Z","caller":"traceutil/trace.go:171","msg":"trace[2089081353] linearizableReadLoop","detail":"{readStateIndex:1777; appliedIndex:1775; }","duration":"259.04662ms","start":"2026-05-15T15:26:57.382242Z","end":"2026-05-15T15:26:57.641288Z","steps":["trace[2089081353] 'read index received' (duration: 26.002514ms)","trace[2089081353] 'applied index is now lower than readState.Index' (duration: 233.041426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:26:57.641468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:26:57.211041Z","time spent":"430.298641ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:26:57.695926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.654535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:26:57.695934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.005236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:26:57.696026Z","caller":"traceutil/trace.go:171","msg":"trace[607371430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1707; }","duration":"313.790178ms","start":"2026-05-15T15:26:57.382205Z","end":"2026-05-15T15:26:57.695995Z","steps":["trace[607371430] 'agreement among raft nodes before linearized reading' (duration: 259.206944ms)","trace[607371430] 'range keys from in-memory index tree' (duration: 54.44975ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:26:57.696031Z","caller":"traceutil/trace.go:171","msg":"trace[20499057] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1707; }","duration":"270.175971ms","start":"2026-05-15T15:26:57.425834Z","end":"2026-05-15T15:26:57.69601Z","steps":["trace[20499057] 'agreement among raft nodes before linearized reading' (duration: 269.993186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:26:57.696077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:26:57.38219Z","time spent":"313.87691ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T15:27:00.913199Z","caller":"traceutil/trace.go:171","msg":"trace[1900364743] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"190.511045ms","start":"2026-05-15T15:27:00.722662Z","end":"2026-05-15T15:27:00.913173Z","steps":["trace[1900364743] 'process raft request' (duration: 190.144586ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:27:01.07079Z","caller":"traceutil/trace.go:171","msg":"trace[1158736234] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"154.336315ms","start":"2026-05-15T15:27:00.915877Z","end":"2026-05-15T15:27:01.070213Z","steps":["trace[1158736234] 'process raft request' (duration: 101.510706ms)","trace[1158736234] 'compare' (duration: 52.46746ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:27:01.526455Z","caller":"traceutil/trace.go:171","msg":"trace[32026596] linearizableReadLoop","detail":"{readStateIndex:1796; appliedIndex:1795; }","duration":"143.650385ms","start":"2026-05-15T15:27:01.382775Z","end":"2026-05-15T15:27:01.526425Z","steps":["trace[32026596] 'read index received' (duration: 99.944105ms)","trace[32026596] 'applied index is now lower than readState.Index' (duration: 43.70521ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:27:01.526642Z","caller":"traceutil/trace.go:171","msg":"trace[808761300] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"385.954014ms","start":"2026-05-15T15:27:01.140666Z","end":"2026-05-15T15:27:01.52662Z","steps":["trace[808761300] 'process raft request' (duration: 342.124821ms)","trace[808761300] 'compare' (duration: 43.517175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:27:01.52679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:27:01.140645Z","time spent":"386.047627ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:27:01.526977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.1978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:27:01.527043Z","caller":"traceutil/trace.go:171","msg":"trace[188404449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1725; }","duration":"144.290441ms","start":"2026-05-15T15:27:01.382735Z","end":"2026-05-15T15:27:01.527026Z","steps":["trace[188404449] 'agreement among raft nodes before linearized reading' (duration: 144.208429ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:27:04.546239Z","caller":"traceutil/trace.go:171","msg":"trace[955882376] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"316.65178ms","start":"2026-05-15T15:27:04.229562Z","end":"2026-05-15T15:27:04.546214Z","steps":["trace[955882376] 'process raft request' (duration: 316.497476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:27:04.54639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:27:04.22954Z","time spent":"316.772364ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:27:04.912896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.942731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-15T15:27:04.912981Z","caller":"traceutil/trace.go:171","msg":"trace[517517379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1739; }","duration":"279.104025ms","start":"2026-05-15T15:27:04.63386Z","end":"2026-05-15T15:27:04.912964Z","steps":["trace[517517379] 'range keys from in-memory index tree' (duration: 278.061338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.019628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037811729,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:28:10.032662Z","caller":"traceutil/trace.go:171","msg":"trace[811267203] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2121; }","duration":"513.654793ms","start":"2026-05-15T15:28:09.518976Z","end":"2026-05-15T15:28:10.032631Z","steps":["trace[811267203] 'read index received' (duration: 513.439898ms)","trace[811267203] 'applied index is now lower than readState.Index' (duration: 213.465µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:28:10.032744Z","caller":"traceutil/trace.go:171","msg":"trace[2084428937] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"599.666186ms","start":"2026-05-15T15:28:09.433042Z","end":"2026-05-15T15:28:10.032708Z","steps":["trace[2084428937] 'process raft request' (duration: 599.350009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.032801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.800817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:28:10.032843Z","caller":"traceutil/trace.go:171","msg":"trace[480160561] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2037; }","duration":"514.072503ms","start":"2026-05-15T15:28:09.518758Z","end":"2026-05-15T15:28:10.03283Z","steps":["trace[480160561] 'agreement among raft nodes before linearized reading' (duration: 513.984451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.032876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:09.518741Z","time spent":"514.127335ms","remote":"127.0.0.1:58518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-15T15:28:10.03291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:09.433028Z","time spent":"599.798219ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:28:10.033462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.991836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"warn","ts":"2026-05-15T15:28:10.033532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.792621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:28:10.033607Z","caller":"traceutil/trace.go:171","msg":"trace[1901989665] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2037; }","duration":"353.879153ms","start":"2026-05-15T15:28:09.67971Z","end":"2026-05-15T15:28:10.033589Z","steps":["trace[1901989665] 'agreement among raft nodes before linearized reading' (duration: 353.087114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.033653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:09.679656Z","time spent":"353.985896ms","remote":"127.0.0.1:58236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-15T15:28:10.033589Z","caller":"traceutil/trace.go:171","msg":"trace[51819938] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2037; }","duration":"281.188171ms","start":"2026-05-15T15:28:09.752382Z","end":"2026-05-15T15:28:10.03357Z","steps":["trace[51819938] 'agreement among raft nodes before linearized reading' (duration: 280.458033ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:28:10.286598Z","caller":"traceutil/trace.go:171","msg":"trace[1283683877] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"156.752138ms","start":"2026-05-15T15:28:10.129832Z","end":"2026-05-15T15:28:10.286584Z","steps":["trace[1283683877] 'read index received' (duration: 156.619345ms)","trace[1283683877] 'applied index is now lower than readState.Index' (duration: 131.973µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:28:10.286693Z","caller":"traceutil/trace.go:171","msg":"trace[1873405972] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"250.31069ms","start":"2026-05-15T15:28:10.036355Z","end":"2026-05-15T15:28:10.286666Z","steps":["trace[1873405972] 'process raft request' (duration: 250.135476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.286761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.925872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T15:28:10.286777Z","caller":"traceutil/trace.go:171","msg":"trace[1733245087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2038; }","duration":"156.975864ms","start":"2026-05-15T15:28:10.129796Z","end":"2026-05-15T15:28:10.286772Z","steps":["trace[1733245087] 'agreement among raft nodes before linearized reading' (duration: 156.889962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.286906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.966965ms","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":"info","ts":"2026-05-15T15:28:10.286974Z","caller":"traceutil/trace.go:171","msg":"trace[2066717638] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2038; }","duration":"140.061327ms","start":"2026-05-15T15:28:10.146891Z","end":"2026-05-15T15:28:10.286953Z","steps":["trace[2066717638] 'agreement among raft nodes before linearized reading' (duration: 139.886063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:10.287377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.717317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-05-15T15:28:10.287494Z","caller":"traceutil/trace.go:171","msg":"trace[1174183439] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2038; }","duration":"151.896131ms","start":"2026-05-15T15:28:10.135569Z","end":"2026-05-15T15:28:10.287465Z","steps":["trace[1174183439] 'agreement among raft nodes before linearized reading' (duration: 151.193045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:12.883602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278835037811761,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T15:28:12.944694Z","caller":"traceutil/trace.go:171","msg":"trace[552565916] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"592.412621ms","start":"2026-05-15T15:28:12.352249Z","end":"2026-05-15T15:28:12.944662Z","steps":["trace[552565916] 'process raft request' (duration: 591.435528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:12.945207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:12.352231Z","time spent":"592.882992ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:28:12.945548Z","caller":"traceutil/trace.go:171","msg":"trace[2024483047] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2134; }","duration":"562.714478ms","start":"2026-05-15T15:28:12.382803Z","end":"2026-05-15T15:28:12.945517Z","steps":["trace[2024483047] 'read index received' (duration: 560.906405ms)","trace[2024483047] 'applied index is now lower than readState.Index' (duration: 1.806633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:28:12.945673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.862622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:28:12.945721Z","caller":"traceutil/trace.go:171","msg":"trace[1466847639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2050; }","duration":"562.936414ms","start":"2026-05-15T15:28:12.382774Z","end":"2026-05-15T15:28:12.94571Z","steps":["trace[1466847639] 'agreement among raft nodes before linearized reading' (duration: 562.834332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:12.945756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:12.38276Z","time spent":"562.989915ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:28:12.945883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.502219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-15T15:28:12.945911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.606954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-15T15:28:12.945954Z","caller":"traceutil/trace.go:171","msg":"trace[872071673] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2050; }","duration":"130.608641ms","start":"2026-05-15T15:28:12.815325Z","end":"2026-05-15T15:28:12.945933Z","steps":["trace[872071673] 'agreement among raft nodes before linearized reading' (duration: 130.428207ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:28:12.946008Z","caller":"traceutil/trace.go:171","msg":"trace[1073112701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2050; }","duration":"496.721127ms","start":"2026-05-15T15:28:12.449268Z","end":"2026-05-15T15:28:12.945989Z","steps":["trace[1073112701] 'agreement among raft nodes before linearized reading' (duration: 496.501302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:12.94613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:12.449263Z","time spent":"496.85531ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T15:28:12.946824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.810748ms","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:5 size:1050264"} {"level":"info","ts":"2026-05-15T15:28:12.946899Z","caller":"traceutil/trace.go:171","msg":"trace[1732884765] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2050; }","duration":"526.924831ms","start":"2026-05-15T15:28:12.419955Z","end":"2026-05-15T15:28:12.946879Z","steps":["trace[1732884765] 'agreement among raft nodes before linearized reading' (duration: 525.727542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:12.946952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:28:12.419941Z","time spent":"526.996943ms","remote":"127.0.0.1:58336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050288,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-15T15:28:53.33496Z","caller":"traceutil/trace.go:171","msg":"trace[1822162015] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"210.265552ms","start":"2026-05-15T15:28:53.124673Z","end":"2026-05-15T15:28:53.334938Z","steps":["trace[1822162015] 'process raft request' (duration: 208.129721ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:28:53.334959Z","caller":"traceutil/trace.go:171","msg":"trace[296879147] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2352; }","duration":"204.441092ms","start":"2026-05-15T15:28:53.130444Z","end":"2026-05-15T15:28:53.334885Z","steps":["trace[296879147] 'read index received' (duration: 202.229178ms)","trace[296879147] 'applied index is now lower than readState.Index' (duration: 2.210604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:28:53.335123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.673268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-15T15:28:53.33518Z","caller":"traceutil/trace.go:171","msg":"trace[269209813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2258; }","duration":"204.79391ms","start":"2026-05-15T15:28:53.130373Z","end":"2026-05-15T15:28:53.335167Z","steps":["trace[269209813] 'agreement among raft nodes before linearized reading' (duration: 204.635787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:53.335347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.612786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T15:28:53.335425Z","caller":"traceutil/trace.go:171","msg":"trace[1639776313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2258; }","duration":"156.80833ms","start":"2026-05-15T15:28:53.178602Z","end":"2026-05-15T15:28:53.33541Z","steps":["trace[1639776313] 'agreement among raft nodes before linearized reading' (duration: 156.591185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:28:58.019187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.630439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T15:29:02.683688Z","caller":"traceutil/trace.go:171","msg":"trace[1470537659] linearizableReadLoop","detail":"{readStateIndex:2401; appliedIndex:2400; }","duration":"331.939758ms","start":"2026-05-15T15:29:02.351723Z","end":"2026-05-15T15:29:02.683663Z","steps":["trace[1470537659] 'read index received' (duration: 331.713643ms)","trace[1470537659] 'applied index is now lower than readState.Index' (duration: 225.245µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:29:02.684098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.322588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:29:02.684274Z","caller":"traceutil/trace.go:171","msg":"trace[1260297294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2305; }","duration":"302.525452ms","start":"2026-05-15T15:29:02.381721Z","end":"2026-05-15T15:29:02.684247Z","steps":["trace[1260297294] 'agreement among raft nodes before linearized reading' (duration: 302.292747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:02.684789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.032835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-15T15:29:02.684925Z","caller":"traceutil/trace.go:171","msg":"trace[281201105] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:2305; }","duration":"333.232709ms","start":"2026-05-15T15:29:02.351666Z","end":"2026-05-15T15:29:02.684899Z","steps":["trace[281201105] 'agreement among raft nodes before linearized reading' (duration: 332.772468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:02.684973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.351646Z","time spent":"333.317602ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-15T15:29:02.684993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.381711Z","time spent":"303.171728ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T15:29:02.684534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.275136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:29:02.68421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.349391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-15T15:29:02.685494Z","caller":"traceutil/trace.go:171","msg":"trace[1030376900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2305; }","duration":"149.688174ms","start":"2026-05-15T15:29:02.535783Z","end":"2026-05-15T15:29:02.685471Z","steps":["trace[1030376900] 'agreement among raft nodes before linearized reading' (duration: 148.28774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:02.684276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.903099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:29:02.685726Z","caller":"traceutil/trace.go:171","msg":"trace[90704217] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2305; }","duration":"116.381744ms","start":"2026-05-15T15:29:02.56932Z","end":"2026-05-15T15:29:02.685702Z","steps":["trace[90704217] 'agreement among raft nodes before linearized reading' (duration: 114.920669ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:29:02.684624Z","caller":"traceutil/trace.go:171","msg":"trace[1907288586] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"349.227524ms","start":"2026-05-15T15:29:02.33533Z","end":"2026-05-15T15:29:02.684558Z","steps":["trace[1907288586] 'process raft request' (duration: 348.174779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:02.686034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.335306Z","time spent":"350.557085ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:29:02.685366Z","caller":"traceutil/trace.go:171","msg":"trace[694321700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2305; }","duration":"223.170688ms","start":"2026-05-15T15:29:02.462135Z","end":"2026-05-15T15:29:02.685305Z","steps":["trace[694321700] 'agreement among raft nodes before linearized reading' (duration: 222.255026ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:29:03.260194Z","caller":"traceutil/trace.go:171","msg":"trace[1503266476] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2401; }","duration":"205.036801ms","start":"2026-05-15T15:29:03.055123Z","end":"2026-05-15T15:29:03.26016Z","steps":["trace[1503266476] 'read index received' (duration: 164.445757ms)","trace[1503266476] 'applied index is now lower than readState.Index' (duration: 40.587724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:29:03.260293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.6871Z","time spent":"573.19072ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-15T15:29:03.260368Z","caller":"traceutil/trace.go:171","msg":"trace[2028976377] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"570.067975ms","start":"2026-05-15T15:29:02.690272Z","end":"2026-05-15T15:29:03.26034Z","steps":["trace[2028976377] 'process raft request' (duration: 569.850189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:03.260522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.690262Z","time spent":"570.187347ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:29:03.260624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.494953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-15T15:29:03.260664Z","caller":"traceutil/trace.go:171","msg":"trace[818246619] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2307; }","duration":"205.578175ms","start":"2026-05-15T15:29:03.055072Z","end":"2026-05-15T15:29:03.26065Z","steps":["trace[818246619] 'agreement among raft nodes before linearized reading' (duration: 205.3778ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:29:03.260718Z","caller":"traceutil/trace.go:171","msg":"trace[295780426] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"571.635352ms","start":"2026-05-15T15:29:02.689059Z","end":"2026-05-15T15:29:03.260694Z","steps":["trace[295780426] 'process raft request' (duration: 570.956426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:03.260891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:29:02.689043Z","time spent":"571.766216ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:29:35.587432Z","caller":"traceutil/trace.go:171","msg":"trace[1235126902] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2566; }","duration":"200.972865ms","start":"2026-05-15T15:29:35.386435Z","end":"2026-05-15T15:29:35.587408Z","steps":["trace[1235126902] 'read index received' (duration: 200.914004ms)","trace[1235126902] 'applied index is now lower than readState.Index' (duration: 57.871µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:29:35.587552Z","caller":"traceutil/trace.go:171","msg":"trace[432711320] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"203.27256ms","start":"2026-05-15T15:29:35.384269Z","end":"2026-05-15T15:29:35.587542Z","steps":["trace[432711320] 'process raft request' (duration: 203.008424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:35.587777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.315573ms","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-05-15T15:29:35.587971Z","caller":"traceutil/trace.go:171","msg":"trace[2108364371] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2463; }","duration":"201.516488ms","start":"2026-05-15T15:29:35.386431Z","end":"2026-05-15T15:29:35.587948Z","steps":["trace[2108364371] 'agreement among raft nodes before linearized reading' (duration: 201.16886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:29:35.612668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.851404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-15T15:29:35.612736Z","caller":"traceutil/trace.go:171","msg":"trace[1465630655] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2464; }","duration":"148.967297ms","start":"2026-05-15T15:29:35.463753Z","end":"2026-05-15T15:29:35.612721Z","steps":["trace[1465630655] 'agreement among raft nodes before linearized reading' (duration: 148.750201ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:29:35.612656Z","caller":"traceutil/trace.go:171","msg":"trace[355730121] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"140.589125ms","start":"2026-05-15T15:29:35.472033Z","end":"2026-05-15T15:29:35.612622Z","steps":["trace[355730121] 'process raft request' (duration: 140.295868ms)"],"step_count":1}