{"level":"warn","ts":"2026-05-19T18:25:53.384291Z","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-19T18:25:53.3844Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.143:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.143:2380","--initial-cluster=instance=https://199.19.213.143:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.143:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.143: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-19T18:25:53.384772Z","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-19T18:25:53.38479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.143:2380"]} {"level":"info","ts":"2026-05-19T18:25:53.384947Z","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-19T18:25:53.385574Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"]} {"level":"info","ts":"2026-05-19T18:25:53.385688Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.143:2380"],"listen-peer-urls":["https://199.19.213.143:2380"],"advertise-client-urls":["https://199.19.213.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.143: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-19T18:25:53.389518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.567322ms"} {"level":"info","ts":"2026-05-19T18:25:53.401417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e5bed6df0e44a0b1","cluster-id":"4e2f0a715964d622"} {"level":"info","ts":"2026-05-19T18:25:53.401532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:25:53.401557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became follower at term 0"} {"level":"info","ts":"2026-05-19T18:25:53.401568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e5bed6df0e44a0b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:25:53.40158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became follower at term 1"} {"level":"info","ts":"2026-05-19T18:25:53.401645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=(16554905533765951665)"} {"level":"warn","ts":"2026-05-19T18:25:53.409023Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:25:53.410263Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:25:53.412593Z","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-19T18:25:53.414855Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e5bed6df0e44a0b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:25:53.417766Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e5bed6df0e44a0b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:25:53.418012Z","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-19T18:25:53.418215Z","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-19T18:25:53.418227Z","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-19T18:25:53.419981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=(16554905533765951665)"} {"level":"info","ts":"2026-05-19T18:25:53.420407Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e2f0a715964d622","local-member-id":"e5bed6df0e44a0b1","added-peer-id":"e5bed6df0e44a0b1","added-peer-peer-urls":["https://199.19.213.143:2380"]} {"level":"info","ts":"2026-05-19T18:25:53.420574Z","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-19T18:25:53.420957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e5bed6df0e44a0b1","initial-advertise-peer-urls":["https://199.19.213.143:2380"],"listen-peer-urls":["https://199.19.213.143:2380"],"advertise-client-urls":["https://199.19.213.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:25:53.421036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:25:53.420733Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.143:2380"} {"level":"info","ts":"2026-05-19T18:25:53.421185Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.143:2380"} {"level":"info","ts":"2026-05-19T18:25:54.302698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:25:54.30277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:25:54.302793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 received MsgPreVoteResp from e5bed6df0e44a0b1 at term 1"} {"level":"info","ts":"2026-05-19T18:25:54.302812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:25:54.302822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 received MsgVoteResp from e5bed6df0e44a0b1 at term 2"} {"level":"info","ts":"2026-05-19T18:25:54.302842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became leader at term 2"} {"level":"info","ts":"2026-05-19T18:25:54.302864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e5bed6df0e44a0b1 elected leader e5bed6df0e44a0b1 at term 2"} {"level":"info","ts":"2026-05-19T18:25:54.305378Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:54.306176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:25:54.306162Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e5bed6df0e44a0b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.143:2379]}","request-path":"/0/members/e5bed6df0e44a0b1/attributes","cluster-id":"4e2f0a715964d622","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:25:54.306248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:25:54.307003Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e2f0a715964d622","local-member-id":"e5bed6df0e44a0b1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:54.30712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:54.307168Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:54.308177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:25:54.308204Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:25:54.310147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T18:25:54.314405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.143:2379"} {"level":"warn","ts":"2026-05-19T18:25:59.962145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.180753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:25:59.962333Z","caller":"traceutil/trace.go:171","msg":"trace[1126972721] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"672.941097ms","start":"2026-05-19T18:25:59.289358Z","end":"2026-05-19T18:25:59.962299Z","steps":["trace[1126972721] 'process raft request' (duration: 33.616613ms)","trace[1126972721] 'compare' (duration: 635.034303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:59.96244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.289351Z","time spent":"673.050171ms","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:25:59.962581Z","caller":"traceutil/trace.go:171","msg":"trace[1139526525] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"382.259984ms","start":"2026-05-19T18:25:59.580282Z","end":"2026-05-19T18:25:59.962542Z","steps":["trace[1139526525] 'process raft request' (duration: 381.992857ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:59.962626Z","caller":"traceutil/trace.go:171","msg":"trace[1685038047] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"457.125167ms","start":"2026-05-19T18:25:59.505459Z","end":"2026-05-19T18:25:59.962585Z","steps":["trace[1685038047] 'read index received' (duration: 33.782µs)","trace[1685038047] 'applied index is now lower than readState.Index' (duration: 457.089725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:59.96265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.580267Z","time spent":"382.351657ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:25:59.962795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.305912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:25:59.962825Z","caller":"traceutil/trace.go:171","msg":"trace[897778694] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:234; }","duration":"457.431385ms","start":"2026-05-19T18:25:59.505384Z","end":"2026-05-19T18:25:59.962816Z","steps":["trace[897778694] 'agreement among raft nodes before linearized reading' (duration: 457.316082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:59.962848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.505366Z","time spent":"457.476216ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-05-19T18:26:00.462796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048816426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:00.96334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048816426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:00.978278Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.242283072s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T18:26:00.978824Z","caller":"traceutil/trace.go:171","msg":"trace[90854364] transaction","detail":"{read_only:false; number_of_response:0; response_revision:234; }","duration":"1.299008449s","start":"2026-05-19T18:25:59.679772Z","end":"2026-05-19T18:26:00.97878Z","steps":["trace[90854364] 'process raft request' (duration: 1.298723681s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.978877Z","caller":"traceutil/trace.go:171","msg":"trace[502028615] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"1.267403407s","start":"2026-05-19T18:25:59.711451Z","end":"2026-05-19T18:26:00.978855Z","steps":["trace[502028615] 'process raft request' (duration: 1.267363596s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.978931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.679752Z","time spent":"1.299126541s","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:00.978932Z","caller":"traceutil/trace.go:171","msg":"trace[1080363999] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"1.27220488s","start":"2026-05-19T18:25:59.706717Z","end":"2026-05-19T18:26:00.978922Z","steps":["trace[1080363999] 'process raft request' (duration: 1.271918403s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.978994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.71143Z","time spent":"1.267506609s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:26:00.979005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.706688Z","time spent":"1.272279012s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:00.978992Z","caller":"traceutil/trace.go:171","msg":"trace[759322814] transaction","detail":"{read_only:false; number_of_response:0; response_revision:236; }","duration":"1.269598603s","start":"2026-05-19T18:25:59.709372Z","end":"2026-05-19T18:26:00.97897Z","steps":["trace[759322814] 'process raft request' (duration: 1.269420919s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.979003Z","caller":"traceutil/trace.go:171","msg":"trace[1712529070] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"1.271331017s","start":"2026-05-19T18:25:59.707624Z","end":"2026-05-19T18:26:00.978955Z","steps":["trace[1712529070] 'process raft request' (duration: 1.271112551s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.978871Z","caller":"traceutil/trace.go:171","msg":"trace[669305372] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"1.271830391s","start":"2026-05-19T18:25:59.707017Z","end":"2026-05-19T18:26:00.978847Z","steps":["trace[669305372] 'process raft request' (duration: 1.271690758s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.979097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.709358Z","time spent":"1.269690507s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:26:00.979137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.707609Z","time spent":"1.271454712s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:26:00.979166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.707003Z","time spent":"1.272093979s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:00.983404Z","caller":"traceutil/trace.go:171","msg":"trace[681071267] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:240; }","duration":"1.020703043s","start":"2026-05-19T18:25:59.962679Z","end":"2026-05-19T18:26:00.983382Z","steps":["trace[681071267] 'read index received' (duration: 1.015834598s)","trace[681071267] 'applied index is now lower than readState.Index' (duration: 4.867225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:00.983517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153574545s","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-19T18:26:00.983525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1533856s","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-19T18:26:00.983675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098114251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:00.983703Z","caller":"traceutil/trace.go:171","msg":"trace[185111718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:238; }","duration":"1.153596766s","start":"2026-05-19T18:25:59.830097Z","end":"2026-05-19T18:26:00.983694Z","steps":["trace[185111718] 'agreement among raft nodes before linearized reading' (duration: 1.153325569s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.983711Z","caller":"traceutil/trace.go:171","msg":"trace[2084216455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:238; }","duration":"1.098176043s","start":"2026-05-19T18:25:59.885525Z","end":"2026-05-19T18:26:00.983701Z","steps":["trace[2084216455] 'agreement among raft nodes before linearized reading' (duration: 1.098101671s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.983743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.885512Z","time spent":"1.098223004s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T18:26:00.983767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018302672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:00.983833Z","caller":"traceutil/trace.go:171","msg":"trace[1246625124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:238; }","duration":"1.018390544s","start":"2026-05-19T18:25:59.965426Z","end":"2026-05-19T18:26:00.983816Z","steps":["trace[1246625124] 'agreement among raft nodes before linearized reading' (duration: 1.018267971s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.983868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.965416Z","time spent":"1.018445925s","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"warn","ts":"2026-05-19T18:26:00.983952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01435524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:00.983743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.830086Z","time spent":"1.153645917s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T18:26:00.983995Z","caller":"traceutil/trace.go:171","msg":"trace[812505833] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:238; }","duration":"1.014409412s","start":"2026-05-19T18:25:59.969576Z","end":"2026-05-19T18:26:00.983985Z","steps":["trace[812505833] 'agreement among raft nodes before linearized reading' (duration: 1.0143345s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.984035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.969569Z","time spent":"1.014458713s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-19T18:26:00.983611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403957212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:00.984157Z","caller":"traceutil/trace.go:171","msg":"trace[1247466811] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:238; }","duration":"1.404529617s","start":"2026-05-19T18:25:59.579617Z","end":"2026-05-19T18:26:00.984146Z","steps":["trace[1247466811] 'agreement among raft nodes before linearized reading' (duration: 1.403973043s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.98365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404100226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-19T18:26:00.984204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.579601Z","time spent":"1.404593269s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-19T18:26:00.984219Z","caller":"traceutil/trace.go:171","msg":"trace[1408831171] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:238; }","duration":"1.404683832s","start":"2026-05-19T18:25:59.579516Z","end":"2026-05-19T18:26:00.9842Z","steps":["trace[1408831171] 'agreement among raft nodes before linearized reading' (duration: 1.404108687s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.983632Z","caller":"traceutil/trace.go:171","msg":"trace[1433214459] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"1.017108062s","start":"2026-05-19T18:25:59.966506Z","end":"2026-05-19T18:26:00.983614Z","steps":["trace[1433214459] 'process raft request' (duration: 1.016776103s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.984281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.579502Z","time spent":"1.404760663s","remote":"127.0.0.1:45976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-19T18:26:00.983573Z","caller":"traceutil/trace.go:171","msg":"trace[1574924433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:238; }","duration":"1.153675397s","start":"2026-05-19T18:25:59.829886Z","end":"2026-05-19T18:26:00.983562Z","steps":["trace[1574924433] 'agreement among raft nodes before linearized reading' (duration: 1.153582125s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:00.984458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.829865Z","time spent":"1.154583111s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:26:00.984319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:59.96648Z","time spent":"1.017790188s","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:01.144003Z","caller":"traceutil/trace.go:171","msg":"trace[1525236367] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"120.927194ms","start":"2026-05-19T18:26:01.023042Z","end":"2026-05-19T18:26:01.143969Z","steps":["trace[1525236367] 'process raft request' (duration: 117.801484ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:01.144087Z","caller":"traceutil/trace.go:171","msg":"trace[2071566445] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:266; }","duration":"108.664439ms","start":"2026-05-19T18:26:01.035395Z","end":"2026-05-19T18:26:01.14406Z","steps":["trace[2071566445] 'read index received' (duration: 105.381965ms)","trace[2071566445] 'applied index is now lower than readState.Index' (duration: 3.280834ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:01.144154Z","caller":"traceutil/trace.go:171","msg":"trace[867395837] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"119.720053ms","start":"2026-05-19T18:26:01.024393Z","end":"2026-05-19T18:26:01.144113Z","steps":["trace[867395837] 'process raft request' (duration: 119.437146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:01.144527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.104941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:01.144589Z","caller":"traceutil/trace.go:171","msg":"trace[2034851597] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:259; }","duration":"109.206324ms","start":"2026-05-19T18:26:01.035365Z","end":"2026-05-19T18:26:01.144572Z","steps":["trace[2034851597] 'agreement among raft nodes before linearized reading' (duration: 108.823103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.062825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.502364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-19T18:26:05.062952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.453816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-19T18:26:05.063071Z","caller":"traceutil/trace.go:171","msg":"trace[874732056] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:311; }","duration":"348.593139ms","start":"2026-05-19T18:26:04.71446Z","end":"2026-05-19T18:26:05.063053Z","steps":["trace[874732056] 'range keys from in-memory index tree' (duration: 348.381865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.063092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.36783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-19T18:26:05.063133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.714449Z","time spent":"348.667362ms","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-19T18:26:05.063145Z","caller":"traceutil/trace.go:171","msg":"trace[789880696] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:311; }","duration":"275.447182ms","start":"2026-05-19T18:26:04.787681Z","end":"2026-05-19T18:26:05.063128Z","steps":["trace[789880696] 'range keys from in-memory index tree' (duration: 275.271367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.062865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.131664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-19T18:26:05.065175Z","caller":"traceutil/trace.go:171","msg":"trace[23328980] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:311; }","duration":"226.536996ms","start":"2026-05-19T18:26:04.838596Z","end":"2026-05-19T18:26:05.065133Z","steps":["trace[23328980] 'range keys from in-memory index tree' (duration: 224.018571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:05.063025Z","caller":"traceutil/trace.go:171","msg":"trace[1887266293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"374.817452ms","start":"2026-05-19T18:26:04.688184Z","end":"2026-05-19T18:26:05.063001Z","steps":["trace[1887266293] 'range keys from in-memory index tree' (duration: 374.370661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.065518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.688172Z","time spent":"377.261465ms","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-19T18:26:05.066042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.948096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T18:26:05.066118Z","caller":"traceutil/trace.go:171","msg":"trace[1177860020] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:311; }","duration":"176.025288ms","start":"2026-05-19T18:26:04.890069Z","end":"2026-05-19T18:26:05.066094Z","steps":["trace[1177860020] 'range keys from in-memory index tree' (duration: 175.850404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.066338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-19T18:26:05.066418Z","caller":"traceutil/trace.go:171","msg":"trace[1043248842] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"126.804966ms","start":"2026-05-19T18:26:04.939584Z","end":"2026-05-19T18:26:05.066389Z","steps":["trace[1043248842] 'range keys from in-memory index tree' (duration: 126.56319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.071028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.786029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-19T18:26:05.071113Z","caller":"traceutil/trace.go:171","msg":"trace[570709461] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:311; }","duration":"332.547147ms","start":"2026-05-19T18:26:04.738535Z","end":"2026-05-19T18:26:05.071082Z","steps":["trace[570709461] 'range keys from in-memory index tree' (duration: 324.405628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.071271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.738513Z","time spent":"332.736562ms","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-05-19T18:26:05.890514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048816650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:07.00377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.609825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:07.003989Z","caller":"traceutil/trace.go:171","msg":"trace[102880599] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"855.37128ms","start":"2026-05-19T18:26:06.148597Z","end":"2026-05-19T18:26:07.003968Z","steps":["trace[102880599] 'process raft request' (duration: 855.287448ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:07.004067Z","caller":"traceutil/trace.go:171","msg":"trace[1207349073] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.653984583s","start":"2026-05-19T18:26:05.35006Z","end":"2026-05-19T18:26:07.004045Z","steps":["trace[1207349073] 'process raft request' (duration: 829.822864ms)","trace[1207349073] 'compare' (duration: 823.132883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:07.004083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:06.148579Z","time spent":"855.454812ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:07.004172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.350033Z","time spent":"1.654086186s","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:26:07.004224Z","caller":"traceutil/trace.go:171","msg":"trace[1729833298] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"1.613857074s","start":"2026-05-19T18:26:05.390337Z","end":"2026-05-19T18:26:07.004194Z","steps":["trace[1729833298] 'read index received' (duration: 789.527561ms)","trace[1729833298] 'applied index is now lower than readState.Index' (duration: 824.326433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:07.004422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614061639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-19T18:26:07.004463Z","caller":"traceutil/trace.go:171","msg":"trace[1174654061] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:337; }","duration":"1.614167191s","start":"2026-05-19T18:26:05.390279Z","end":"2026-05-19T18:26:07.004446Z","steps":["trace[1174654061] 'agreement among raft nodes before linearized reading' (duration: 1.614056658s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.004466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610800914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-05-19T18:26:07.004515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.390251Z","time spent":"1.614255334s","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-05-19T18:26:07.004526Z","caller":"traceutil/trace.go:171","msg":"trace[2046501465] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:337; }","duration":"1.610873917s","start":"2026-05-19T18:26:05.393637Z","end":"2026-05-19T18:26:07.004511Z","steps":["trace[2046501465] 'agreement among raft nodes before linearized reading' (duration: 1.610772994s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.004529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565341237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-19T18:26:07.004528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30521957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:07.004539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366722048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-19T18:26:07.004569Z","caller":"traceutil/trace.go:171","msg":"trace[94821943] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:337; }","duration":"1.565432959s","start":"2026-05-19T18:26:05.439126Z","end":"2026-05-19T18:26:07.004558Z","steps":["trace[94821943] 'agreement among raft nodes before linearized reading' (duration: 1.565357857s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.004576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.39363Z","time spent":"1.610933348s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-19T18:26:07.004597Z","caller":"traceutil/trace.go:171","msg":"trace[957751689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:337; }","duration":"1.305333552s","start":"2026-05-19T18:26:05.699246Z","end":"2026-05-19T18:26:07.00458Z","steps":["trace[957751689] 'agreement among raft nodes before linearized reading' (duration: 1.30523197s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.004606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.439096Z","time spent":"1.565502241s","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-19T18:26:07.00461Z","caller":"traceutil/trace.go:171","msg":"trace[646899345] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:337; }","duration":"1.366864752s","start":"2026-05-19T18:26:05.637726Z","end":"2026-05-19T18:26:07.004591Z","steps":["trace[646899345] 'agreement among raft nodes before linearized reading' (duration: 1.366738799s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.004631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.699219Z","time spent":"1.305406175s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:26:07.004656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.637694Z","time spent":"1.366953315s","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-19T18:26:27.445093Z","caller":"traceutil/trace.go:171","msg":"trace[483121354] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"117.070826ms","start":"2026-05-19T18:26:27.328005Z","end":"2026-05-19T18:26:27.445076Z","steps":["trace[483121354] 'process raft request' (duration: 116.973363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.725506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.146084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:27.725602Z","caller":"traceutil/trace.go:171","msg":"trace[1569430934] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:492; }","duration":"361.388078ms","start":"2026-05-19T18:26:27.364199Z","end":"2026-05-19T18:26:27.725587Z","steps":["trace[1569430934] 'read index received' (duration: 80.860546ms)","trace[1569430934] 'applied index is now lower than readState.Index' (duration: 280.526422ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:27.725713Z","caller":"traceutil/trace.go:171","msg":"trace[88538444] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"395.432042ms","start":"2026-05-19T18:26:27.330248Z","end":"2026-05-19T18:26:27.72568Z","steps":["trace[88538444] 'process raft request' (duration: 201.027341ms)","trace[88538444] 'compare' (duration: 194.044962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:27.725803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.633965ms","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-19T18:26:27.725839Z","caller":"traceutil/trace.go:171","msg":"trace[131678850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"361.702806ms","start":"2026-05-19T18:26:27.364125Z","end":"2026-05-19T18:26:27.725827Z","steps":["trace[131678850] 'agreement among raft nodes before linearized reading' (duration: 361.639415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.725859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:27.330237Z","time spent":"395.530704ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:27.725886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:27.364081Z","time spent":"361.796689ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:26:27.738484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.965377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:27.738608Z","caller":"traceutil/trace.go:171","msg":"trace[1110797755] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:480; }","duration":"294.129742ms","start":"2026-05-19T18:26:27.44446Z","end":"2026-05-19T18:26:27.73859Z","steps":["trace[1110797755] 'agreement among raft nodes before linearized reading' (duration: 293.937457ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:27.738925Z","caller":"traceutil/trace.go:171","msg":"trace[1353684827] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"266.224015ms","start":"2026-05-19T18:26:27.472689Z","end":"2026-05-19T18:26:27.738913Z","steps":["trace[1353684827] 'process raft request' (duration: 265.579418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:28.49602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048816941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:26:28.618151Z","caller":"traceutil/trace.go:171","msg":"trace[1396289830] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"623.025965ms","start":"2026-05-19T18:26:27.995104Z","end":"2026-05-19T18:26:28.61813Z","steps":["trace[1396289830] 'read index received' (duration: 622.84376ms)","trace[1396289830] 'applied index is now lower than readState.Index' (duration: 181.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:28.618211Z","caller":"traceutil/trace.go:171","msg":"trace[925117587] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"889.017654ms","start":"2026-05-19T18:26:27.729163Z","end":"2026-05-19T18:26:28.618181Z","steps":["trace[925117587] 'process raft request' (duration: 888.83396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:28.618352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.224151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:28.618376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:27.729149Z","time spent":"889.128848ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:28.61839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.999111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.143\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T18:26:28.618445Z","caller":"traceutil/trace.go:171","msg":"trace[875342775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"623.370864ms","start":"2026-05-19T18:26:27.995053Z","end":"2026-05-19T18:26:28.618424Z","steps":["trace[875342775] 'agreement among raft nodes before linearized reading' (duration: 623.19293ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:28.618462Z","caller":"traceutil/trace.go:171","msg":"trace[1395952333] range","detail":"{range_begin:/registry/masterleases/199.19.213.143; range_end:; response_count:1; response_revision:481; }","duration":"575.124625ms","start":"2026-05-19T18:26:28.043321Z","end":"2026-05-19T18:26:28.618446Z","steps":["trace[1395952333] 'agreement among raft nodes before linearized reading' (duration: 574.984481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:28.618497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:28.043305Z","time spent":"575.184816ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.143\" "} {"level":"warn","ts":"2026-05-19T18:26:28.618505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:27.995033Z","time spent":"623.463216ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:26:28.618668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.981998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:28.618909Z","caller":"traceutil/trace.go:171","msg":"trace[1405081543] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:481; }","duration":"555.348988ms","start":"2026-05-19T18:26:28.063523Z","end":"2026-05-19T18:26:28.618872Z","steps":["trace[1405081543] 'agreement among raft nodes before linearized reading' (duration: 554.863175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:28.619012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:28.063516Z","time spent":"555.476881ms","remote":"127.0.0.1:45676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-19T18:26:29.363889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.451638ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-19T18:26:29.363977Z","caller":"traceutil/trace.go:171","msg":"trace[464830507] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"742.953663ms","start":"2026-05-19T18:26:28.621011Z","end":"2026-05-19T18:26:29.363965Z","steps":["trace[464830507] 'read index received' (duration: 94.392502ms)","trace[464830507] 'applied index is now lower than readState.Index' (duration: 648.560051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:29.364013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:28.619766Z","time spent":"744.239147ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T18:26:29.364082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.068907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:29.364121Z","caller":"traceutil/trace.go:171","msg":"trace[890534217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"743.179329ms","start":"2026-05-19T18:26:28.62093Z","end":"2026-05-19T18:26:29.364109Z","steps":["trace[890534217] 'agreement among raft nodes before linearized reading' (duration: 743.107287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:29.364165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:28.620909Z","time spent":"743.247291ms","remote":"127.0.0.1:45580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:26:29.364396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.3956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:29.364435Z","caller":"traceutil/trace.go:171","msg":"trace[571352281] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:481; }","duration":"691.472052ms","start":"2026-05-19T18:26:28.672952Z","end":"2026-05-19T18:26:29.364424Z","steps":["trace[571352281] 'agreement among raft nodes before linearized reading' (duration: 691.417801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:29.36446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:28.672931Z","time spent":"691.522664ms","remote":"127.0.0.1:45676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-19T18:26:29.36465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.453776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T18:26:29.36472Z","caller":"traceutil/trace.go:171","msg":"trace[1920393689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"117.564608ms","start":"2026-05-19T18:26:29.247135Z","end":"2026-05-19T18:26:29.364699Z","steps":["trace[1920393689] 'agreement among raft nodes before linearized reading' (duration: 117.432775ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:29.478731Z","caller":"traceutil/trace.go:171","msg":"trace[1637003712] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"106.118924ms","start":"2026-05-19T18:26:29.372598Z","end":"2026-05-19T18:26:29.478717Z","steps":["trace[1637003712] 'process raft request' (duration: 106.008302ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:29.478747Z","caller":"traceutil/trace.go:171","msg":"trace[480587443] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"113.501964ms","start":"2026-05-19T18:26:29.365208Z","end":"2026-05-19T18:26:29.47871Z","steps":["trace[480587443] 'process raft request' (duration: 99.985657ms)","trace[480587443] 'compare' (duration: 13.262371ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:29.478663Z","caller":"traceutil/trace.go:171","msg":"trace[32929577] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"105.180411ms","start":"2026-05-19T18:26:29.373466Z","end":"2026-05-19T18:26:29.478646Z","steps":["trace[32929577] 'read index received' (duration: 91.738765ms)","trace[32929577] 'applied index is now lower than readState.Index' (duration: 13.440736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:29.478913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.406766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:29.478992Z","caller":"traceutil/trace.go:171","msg":"trace[954638501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"105.53683ms","start":"2026-05-19T18:26:29.373436Z","end":"2026-05-19T18:26:29.478973Z","steps":["trace[954638501] 'agreement among raft nodes before linearized reading' (duration: 105.366066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.359301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.570831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:36.359451Z","caller":"traceutil/trace.go:171","msg":"trace[961425428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:591; }","duration":"595.751955ms","start":"2026-05-19T18:26:35.763675Z","end":"2026-05-19T18:26:36.359427Z","steps":["trace[961425428] 'range keys from in-memory index tree' (duration: 595.505439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.35951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.486589ms","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-19T18:26:36.359596Z","caller":"traceutil/trace.go:171","msg":"trace[284980452] 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:591; }","duration":"574.583402ms","start":"2026-05-19T18:26:35.784986Z","end":"2026-05-19T18:26:36.359569Z","steps":["trace[284980452] 'count revisions from in-memory index tree' (duration: 574.404757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.359682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.784949Z","time spent":"574.712855ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:26:36.35978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.647018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.359728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.562731ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.359831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.758011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:36.359847Z","caller":"traceutil/trace.go:171","msg":"trace[1212288903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:591; }","duration":"612.699869ms","start":"2026-05-19T18:26:35.747113Z","end":"2026-05-19T18:26:36.359813Z","steps":["trace[1212288903] 'count revisions from in-memory index tree' (duration: 612.620547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:36.359891Z","caller":"traceutil/trace.go:171","msg":"trace[1465457] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:591; }","duration":"612.823442ms","start":"2026-05-19T18:26:35.747045Z","end":"2026-05-19T18:26:36.359868Z","steps":["trace[1465457] 'range keys from in-memory index tree' (duration: 612.666058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.359886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.747109Z","time spent":"612.766671ms","remote":"127.0.0.1:49646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:26:36.359979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.74703Z","time spent":"612.935176ms","remote":"127.0.0.1:49646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:26:36.360031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.105569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.359531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.763663Z","time spent":"595.852008ms","remote":"127.0.0.1:49646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-05-19T18:26:36.360096Z","caller":"traceutil/trace.go:171","msg":"trace[126324995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:591; }","duration":"597.227532ms","start":"2026-05-19T18:26:35.762854Z","end":"2026-05-19T18:26:36.360082Z","steps":["trace[126324995] 'count revisions from in-memory index tree' (duration: 597.026088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:36.359878Z","caller":"traceutil/trace.go:171","msg":"trace[362047089] 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:591; }","duration":"574.814337ms","start":"2026-05-19T18:26:35.785031Z","end":"2026-05-19T18:26:36.359846Z","steps":["trace[362047089] 'range keys from in-memory index tree' (duration: 574.431867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.360149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.762835Z","time spent":"597.300845ms","remote":"127.0.0.1:49658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:26:36.360173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.785019Z","time spent":"575.138935ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:26:36.359754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.224002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.36038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.441475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.36039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.289995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-czcf4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T18:26:36.36043Z","caller":"traceutil/trace.go:171","msg":"trace[1629450899] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:591; }","duration":"637.513496ms","start":"2026-05-19T18:26:35.722902Z","end":"2026-05-19T18:26:36.360415Z","steps":["trace[1629450899] 'count revisions from in-memory index tree' (duration: 637.390484ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:36.360436Z","caller":"traceutil/trace.go:171","msg":"trace[421384387] range","detail":"{range_begin:/registry/pods/kube-system/cilium-czcf4; range_end:; response_count:1; response_revision:591; }","duration":"643.364396ms","start":"2026-05-19T18:26:35.717058Z","end":"2026-05-19T18:26:36.360422Z","steps":["trace[421384387] 'range keys from in-memory index tree' (duration: 643.11128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.360472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.722888Z","time spent":"637.574179ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:26:36.360478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.717041Z","time spent":"643.429249ms","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-czcf4\" "} {"level":"warn","ts":"2026-05-19T18:26:36.359798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.584021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.360488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.632061ms","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-19T18:26:36.360557Z","caller":"traceutil/trace.go:171","msg":"trace[404919447] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:591; }","duration":"656.371762ms","start":"2026-05-19T18:26:35.704165Z","end":"2026-05-19T18:26:36.360536Z","steps":["trace[404919447] 'range keys from in-memory index tree' (duration: 655.498099ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:36.360605Z","caller":"traceutil/trace.go:171","msg":"trace[1206398521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"582.70249ms","start":"2026-05-19T18:26:35.77787Z","end":"2026-05-19T18:26:36.360572Z","steps":["trace[1206398521] 'range keys from in-memory index tree' (duration: 581.453757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.360622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.704149Z","time spent":"656.460014ms","remote":"127.0.0.1:45834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-19T18:26:36.359407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.866253ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:36.360673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.777856Z","time spent":"582.791172ms","remote":"127.0.0.1:45870","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-19T18:26:36.360413Z","caller":"traceutil/trace.go:171","msg":"trace[1227181336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:591; }","duration":"597.857689ms","start":"2026-05-19T18:26:35.762466Z","end":"2026-05-19T18:26:36.360324Z","steps":["trace[1227181336] 'range keys from in-memory index tree' (duration: 597.059859ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:36.360711Z","caller":"traceutil/trace.go:171","msg":"trace[750129194] 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:591; }","duration":"575.261549ms","start":"2026-05-19T18:26:35.785438Z","end":"2026-05-19T18:26:36.3607Z","steps":["trace[750129194] 'range keys from in-memory index tree' (duration: 573.767941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.36075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.785419Z","time spent":"575.319031ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T18:26:36.360767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.762451Z","time spent":"598.28973ms","remote":"127.0.0.1:49658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:26:36.361156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.139833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:36.36122Z","caller":"traceutil/trace.go:171","msg":"trace[34247240] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:591; }","duration":"638.229875ms","start":"2026-05-19T18:26:35.722955Z","end":"2026-05-19T18:26:36.361185Z","steps":["trace[34247240] 'range keys from in-memory index tree' (duration: 638.071001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.361255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:35.722939Z","time spent":"638.307487ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-19T18:26:36.530626Z","caller":"traceutil/trace.go:171","msg":"trace[21203215] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"164.350108ms","start":"2026-05-19T18:26:36.366245Z","end":"2026-05-19T18:26:36.530595Z","steps":["trace[21203215] 'process raft request' (duration: 164.01248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:37.01639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.62074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:37.016511Z","caller":"traceutil/trace.go:171","msg":"trace[1611240223] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"322.565891ms","start":"2026-05-19T18:26:36.693925Z","end":"2026-05-19T18:26:37.016491Z","steps":["trace[1611240223] 'read index received' (duration: 3.640003ms)","trace[1611240223] 'applied index is now lower than readState.Index' (duration: 318.924228ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:37.016508Z","caller":"traceutil/trace.go:171","msg":"trace[719433741] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"641.438988ms","start":"2026-05-19T18:26:36.375052Z","end":"2026-05-19T18:26:37.016491Z","steps":["trace[719433741] 'process raft request' (duration: 322.610083ms)","trace[719433741] 'compare' (duration: 318.496547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:37.016591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:36.375033Z","time spent":"641.53058ms","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:37.017103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.182787ms","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-19T18:26:37.017166Z","caller":"traceutil/trace.go:171","msg":"trace[208917121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"323.29079ms","start":"2026-05-19T18:26:36.693861Z","end":"2026-05-19T18:26:37.017151Z","steps":["trace[208917121] 'agreement among raft nodes before linearized reading' (duration: 322.713105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:37.017211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:36.693836Z","time spent":"323.362941ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:26:37.035551Z","caller":"traceutil/trace.go:171","msg":"trace[168048355] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"282.750448ms","start":"2026-05-19T18:26:36.752776Z","end":"2026-05-19T18:26:37.035526Z","steps":["trace[168048355] 'process raft request' (duration: 281.198279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:38.172929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.479456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:38.173188Z","caller":"traceutil/trace.go:171","msg":"trace[1177521460] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"652.474712ms","start":"2026-05-19T18:26:37.520683Z","end":"2026-05-19T18:26:38.173158Z","steps":["trace[1177521460] 'read index received' (duration: 39.271µs)","trace[1177521460] 'applied index is now lower than readState.Index' (duration: 652.431161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:38.173437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.012415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:38.173457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.192547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.143\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T18:26:38.173486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.80032ms","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-19T18:26:38.173566Z","caller":"traceutil/trace.go:171","msg":"trace[762113774] range","detail":"{range_begin:/registry/masterleases/199.19.213.143; range_end:; response_count:1; response_revision:597; }","duration":"129.3558ms","start":"2026-05-19T18:26:38.044185Z","end":"2026-05-19T18:26:38.173541Z","steps":["trace[762113774] 'agreement among raft nodes before linearized reading' (duration: 129.176406ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:38.173572Z","caller":"traceutil/trace.go:171","msg":"trace[1861409973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"652.921673ms","start":"2026-05-19T18:26:37.520635Z","end":"2026-05-19T18:26:38.173557Z","steps":["trace[1861409973] 'agreement among raft nodes before linearized reading' (duration: 652.623775ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:38.17351Z","caller":"traceutil/trace.go:171","msg":"trace[23790894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"179.125458ms","start":"2026-05-19T18:26:37.994365Z","end":"2026-05-19T18:26:38.17349Z","steps":["trace[23790894] 'agreement among raft nodes before linearized reading' (duration: 179.019186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:38.17365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:37.520616Z","time spent":"653.019845ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:26:38.173447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.48014ms","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-19T18:26:38.173809Z","caller":"traceutil/trace.go:171","msg":"trace[2088503472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"648.826948ms","start":"2026-05-19T18:26:37.524952Z","end":"2026-05-19T18:26:38.173779Z","steps":["trace[2088503472] 'agreement among raft nodes before linearized reading' (duration: 648.368857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:38.173913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:37.524878Z","time spent":"649.007862ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T18:26:38.173063Z","caller":"traceutil/trace.go:171","msg":"trace[714155223] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.066004579s","start":"2026-05-19T18:26:37.107027Z","end":"2026-05-19T18:26:38.173032Z","steps":["trace[714155223] 'process raft request' (duration: 285.328606ms)","trace[714155223] 'compare' (duration: 779.863251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:38.17425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:37.10699Z","time spent":"1.067190259s","remote":"127.0.0.1:45834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:40.017465Z","caller":"traceutil/trace.go:171","msg":"trace[838072911] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"153.06359ms","start":"2026-05-19T18:26:39.864369Z","end":"2026-05-19T18:26:40.017433Z","steps":["trace[838072911] 'process raft request' (duration: 152.66502ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.02109Z","caller":"traceutil/trace.go:171","msg":"trace[200060603] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"137.50873ms","start":"2026-05-19T18:26:40.883559Z","end":"2026-05-19T18:26:41.021068Z","steps":["trace[200060603] 'process raft request' (duration: 137.355736ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:51.820001Z","caller":"traceutil/trace.go:171","msg":"trace[151435811] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"110.160388ms","start":"2026-05-19T18:26:51.709812Z","end":"2026-05-19T18:26:51.819973Z","steps":["trace[151435811] 'process raft request' (duration: 109.530692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:52.233662Z","caller":"traceutil/trace.go:171","msg":"trace[1483484413] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"110.804955ms","start":"2026-05-19T18:26:52.122834Z","end":"2026-05-19T18:26:52.233639Z","steps":["trace[1483484413] 'process raft request' (duration: 110.653271ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:52.397464Z","caller":"traceutil/trace.go:171","msg":"trace[1544685478] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"132.484621ms","start":"2026-05-19T18:26:52.264934Z","end":"2026-05-19T18:26:52.397418Z","steps":["trace[1544685478] 'process raft request' (duration: 71.899087ms)","trace[1544685478] 'compare' (duration: 60.227155ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:54.642332Z","caller":"traceutil/trace.go:171","msg":"trace[1436734670] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"240.455215ms","start":"2026-05-19T18:26:54.401856Z","end":"2026-05-19T18:26:54.642311Z","steps":["trace[1436734670] 'read index received' (duration: 240.274459ms)","trace[1436734670] 'applied index is now lower than readState.Index' (duration: 179.796µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:54.642449Z","caller":"traceutil/trace.go:171","msg":"trace[1252218195] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"345.934854ms","start":"2026-05-19T18:26:54.296503Z","end":"2026-05-19T18:26:54.642437Z","steps":["trace[1252218195] 'process raft request' (duration: 345.668656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:54.642543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:54.296482Z","time spent":"345.992265ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:54.642971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.117971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T18:26:54.643016Z","caller":"traceutil/trace.go:171","msg":"trace[443633875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"241.161412ms","start":"2026-05-19T18:26:54.401843Z","end":"2026-05-19T18:26:54.643005Z","steps":["trace[443633875] 'agreement among raft nodes before linearized reading' (duration: 241.039589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:57.48855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.759211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:57.488606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.708201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-49xcr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T18:26:57.488667Z","caller":"traceutil/trace.go:171","msg":"trace[879326907] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-49xcr; range_end:; response_count:1; response_revision:766; }","duration":"657.807753ms","start":"2026-05-19T18:26:56.830847Z","end":"2026-05-19T18:26:57.488654Z","steps":["trace[879326907] 'range keys from in-memory index tree' (duration: 657.534336ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:57.48865Z","caller":"traceutil/trace.go:171","msg":"trace[2052331799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"493.920616ms","start":"2026-05-19T18:26:56.994707Z","end":"2026-05-19T18:26:57.488627Z","steps":["trace[2052331799] 'range keys from in-memory index tree' (duration: 493.67321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:57.488706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:56.830828Z","time spent":"657.867675ms","remote":"127.0.0.1:45776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-49xcr\" "} {"level":"warn","ts":"2026-05-19T18:26:57.488711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:56.994685Z","time spent":"494.014838ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:26:57.488517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.159445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T18:26:57.489203Z","caller":"traceutil/trace.go:171","msg":"trace[813564332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"361.868603ms","start":"2026-05-19T18:26:57.127286Z","end":"2026-05-19T18:26:57.489155Z","steps":["trace[813564332] 'range keys from in-memory index tree' (duration: 360.955929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:57.489288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:57.127242Z","time spent":"362.002886ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:26:57.602437Z","caller":"traceutil/trace.go:171","msg":"trace[1107798763] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"109.03081ms","start":"2026-05-19T18:26:57.493384Z","end":"2026-05-19T18:26:57.602414Z","steps":["trace[1107798763] 'process raft request' (duration: 108.878696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:57.611424Z","caller":"traceutil/trace.go:171","msg":"trace[1807757905] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"114.84279ms","start":"2026-05-19T18:26:57.496547Z","end":"2026-05-19T18:26:57.61139Z","steps":["trace[1807757905] 'process raft request' (duration: 114.559402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.24954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048817946,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:27:26.381845Z","caller":"traceutil/trace.go:171","msg":"trace[408131042] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"651.96469ms","start":"2026-05-19T18:27:25.729859Z","end":"2026-05-19T18:27:26.381824Z","steps":["trace[408131042] 'process raft request' (duration: 651.853178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.381991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.729842Z","time spent":"652.070634ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:26.390899Z","caller":"traceutil/trace.go:171","msg":"trace[2013321648] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"642.398777ms","start":"2026-05-19T18:27:25.74848Z","end":"2026-05-19T18:27:26.390879Z","steps":["trace[2013321648] 'read index received' (duration: 633.301822ms)","trace[2013321648] 'applied index is now lower than readState.Index' (duration: 9.096205ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:26.39097Z","caller":"traceutil/trace.go:171","msg":"trace[386414471] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"604.340799ms","start":"2026-05-19T18:27:25.78662Z","end":"2026-05-19T18:27:26.39096Z","steps":["trace[386414471] 'process raft request' (duration: 604.157544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.391108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.908147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:27:26.391136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.786596Z","time spent":"604.479643ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:26.391156Z","caller":"traceutil/trace.go:171","msg":"trace[267371609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"395.999559ms","start":"2026-05-19T18:27:25.995143Z","end":"2026-05-19T18:27:26.391143Z","steps":["trace[267371609] 'agreement among raft nodes before linearized reading' (duration: 395.926007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.391188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.995124Z","time spent":"396.056781ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:27:26.391529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.052463ms","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-19T18:27:26.391554Z","caller":"traceutil/trace.go:171","msg":"trace[1578557231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"643.111005ms","start":"2026-05-19T18:27:25.748436Z","end":"2026-05-19T18:27:26.391547Z","steps":["trace[1578557231] 'agreement among raft nodes before linearized reading' (duration: 642.604012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.391574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.748423Z","time spent":"643.147225ms","remote":"127.0.0.1:45870","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-19T18:27:27.460582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.021309ms","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-19T18:27:27.460675Z","caller":"traceutil/trace.go:171","msg":"trace[1726490032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"496.144832ms","start":"2026-05-19T18:27:26.964518Z","end":"2026-05-19T18:27:27.460663Z","steps":["trace[1726490032] 'range keys from in-memory index tree' (duration: 495.905846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:27.460704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:26.964499Z","time spent":"496.195823ms","remote":"127.0.0.1:45870","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-19T18:27:27.46075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.803721ms","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-19T18:27:27.460613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.674514ms","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-19T18:27:27.460797Z","caller":"traceutil/trace.go:171","msg":"trace[386224666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"500.880293ms","start":"2026-05-19T18:27:26.959898Z","end":"2026-05-19T18:27:27.460779Z","steps":["trace[386224666] 'range keys from in-memory index tree' (duration: 500.670827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:27.46083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:26.959876Z","time spent":"500.945665ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T18:27:27.460817Z","caller":"traceutil/trace.go:171","msg":"trace[1758760643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"566.891729ms","start":"2026-05-19T18:27:26.893901Z","end":"2026-05-19T18:27:27.460793Z","steps":["trace[1758760643] 'range keys from in-memory index tree' (duration: 566.571671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:27.460909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:26.893883Z","time spent":"566.984631ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T18:27:33.281631Z","caller":"traceutil/trace.go:171","msg":"trace[497756121] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"112.425167ms","start":"2026-05-19T18:27:33.169177Z","end":"2026-05-19T18:27:33.281602Z","steps":["trace[497756121] 'process raft request' (duration: 111.559466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.760114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.551183ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:33.760272Z","caller":"traceutil/trace.go:171","msg":"trace[148869662] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:986; }","duration":"329.736817ms","start":"2026-05-19T18:27:33.430513Z","end":"2026-05-19T18:27:33.76025Z","steps":["trace[148869662] 'range keys from in-memory index tree' (duration: 329.525762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.964877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.941239ms","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-19T18:27:33.96491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.999521ms","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-19T18:27:33.964977Z","caller":"traceutil/trace.go:171","msg":"trace[1474039376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"472.106373ms","start":"2026-05-19T18:27:33.492856Z","end":"2026-05-19T18:27:33.964963Z","steps":["trace[1474039376] 'agreement among raft nodes before linearized reading' (duration: 471.942399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.964974Z","caller":"traceutil/trace.go:171","msg":"trace[1430132861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"472.085663ms","start":"2026-05-19T18:27:33.49287Z","end":"2026-05-19T18:27:33.964956Z","steps":["trace[1430132861] 'agreement among raft nodes before linearized reading' (duration: 471.834547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.965021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:33.492836Z","time spent":"472.173895ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:27:33.96502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:33.492852Z","time spent":"472.157985ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T18:27:33.965178Z","caller":"traceutil/trace.go:171","msg":"trace[43202776] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"471.776175ms","start":"2026-05-19T18:27:33.492907Z","end":"2026-05-19T18:27:33.964683Z","steps":["trace[43202776] 'read index received' (duration: 447.428891ms)","trace[43202776] 'applied index is now lower than readState.Index' (duration: 24.334404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:33.965789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.584121ms","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-19T18:27:33.965829Z","caller":"traceutil/trace.go:171","msg":"trace[1462290057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"471.638972ms","start":"2026-05-19T18:27:33.494178Z","end":"2026-05-19T18:27:33.965817Z","steps":["trace[1462290057] 'agreement among raft nodes before linearized reading' (duration: 471.077807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.965857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:33.494171Z","time spent":"471.679354ms","remote":"127.0.0.1:45870","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-19T18:27:50.611438Z","caller":"traceutil/trace.go:171","msg":"trace[1013334461] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"120.559426ms","start":"2026-05-19T18:27:50.490849Z","end":"2026-05-19T18:27:50.611409Z","steps":["trace[1013334461] 'process raft request' (duration: 120.391541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:56.580712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048818220,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:27:56.943824Z","caller":"traceutil/trace.go:171","msg":"trace[1404815495] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"864.029469ms","start":"2026-05-19T18:27:56.079769Z","end":"2026-05-19T18:27:56.943799Z","steps":["trace[1404815495] 'process raft request' (duration: 863.888435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:56.943974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.079753Z","time spent":"864.145702ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:57.246837Z","caller":"traceutil/trace.go:171","msg":"trace[879964847] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"597.851533ms","start":"2026-05-19T18:27:56.648954Z","end":"2026-05-19T18:27:57.246805Z","steps":["trace[879964847] 'process raft request' (duration: 597.72923ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.246819Z","caller":"traceutil/trace.go:171","msg":"trace[595590032] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.166178439s","start":"2026-05-19T18:27:56.080598Z","end":"2026-05-19T18:27:57.246777Z","steps":["trace[595590032] 'process raft request' (duration: 1.165928522s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.246743Z","caller":"traceutil/trace.go:171","msg":"trace[591967123] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"1.166913827s","start":"2026-05-19T18:27:56.079803Z","end":"2026-05-19T18:27:57.246717Z","steps":["trace[591967123] 'read index received' (duration: 863.937546ms)","trace[591967123] 'applied index is now lower than readState.Index' (duration: 302.975401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:57.246996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.648923Z","time spent":"597.975986ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:27:57.247139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.611163ms","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-19T18:27:57.247104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.080583Z","time spent":"1.166339183s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:57.247195Z","caller":"traceutil/trace.go:171","msg":"trace[1367307694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1061; }","duration":"746.736667ms","start":"2026-05-19T18:27:56.500428Z","end":"2026-05-19T18:27:57.247165Z","steps":["trace[1367307694] 'agreement among raft nodes before linearized reading' (duration: 746.631375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.247222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.50041Z","time spent":"746.803739ms","remote":"127.0.0.1:49508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:27:57.247174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167338918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T18:27:57.24747Z","caller":"traceutil/trace.go:171","msg":"trace[196691849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.167508833s","start":"2026-05-19T18:27:56.079748Z","end":"2026-05-19T18:27:57.247257Z","steps":["trace[196691849] 'agreement among raft nodes before linearized reading' (duration: 1.167186005s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.247529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.079714Z","time spent":"1.1678045s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:27:57.247546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.827288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:57.247565Z","caller":"traceutil/trace.go:171","msg":"trace[234477356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"253.88944ms","start":"2026-05-19T18:27:56.993669Z","end":"2026-05-19T18:27:57.247559Z","steps":["trace[234477356] 'agreement among raft nodes before linearized reading' (duration: 253.854519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.247658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.02685ms","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-19T18:27:57.24771Z","caller":"traceutil/trace.go:171","msg":"trace[886020231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"600.124003ms","start":"2026-05-19T18:27:56.647576Z","end":"2026-05-19T18:27:57.2477Z","steps":["trace[886020231] 'agreement among raft nodes before linearized reading' (duration: 599.949448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.247745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.647552Z","time spent":"600.183754ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:27:57.247746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.028062ms","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-19T18:27:57.247789Z","caller":"traceutil/trace.go:171","msg":"trace[22400871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"574.102484ms","start":"2026-05-19T18:27:56.673673Z","end":"2026-05-19T18:27:57.247776Z","steps":["trace[22400871] 'agreement among raft nodes before linearized reading' (duration: 573.994711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.24783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.673663Z","time spent":"574.158745ms","remote":"127.0.0.1:45870","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-19T18:27:57.642434Z","caller":"traceutil/trace.go:171","msg":"trace[559286645] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"389.79711ms","start":"2026-05-19T18:27:57.2526Z","end":"2026-05-19T18:27:57.642397Z","steps":["trace[559286645] 'process raft request' (duration: 330.474637ms)","trace[559286645] 'compare' (duration: 58.988155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:57.642657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:57.252589Z","time spent":"389.932013ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:57.642685Z","caller":"traceutil/trace.go:171","msg":"trace[197394093] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"389.641276ms","start":"2026-05-19T18:27:57.252877Z","end":"2026-05-19T18:27:57.642518Z","steps":["trace[197394093] 'process raft request' (duration: 389.428061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.64304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:57.252868Z","time spent":"389.975425ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:59.256905Z","caller":"traceutil/trace.go:171","msg":"trace[488133219] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"262.657948ms","start":"2026-05-19T18:27:58.994219Z","end":"2026-05-19T18:27:59.256877Z","steps":["trace[488133219] 'read index received' (duration: 262.619137ms)","trace[488133219] 'applied index is now lower than readState.Index' (duration: 37.811µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:59.25704Z","caller":"traceutil/trace.go:171","msg":"trace[1194306185] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"304.111016ms","start":"2026-05-19T18:27:58.952918Z","end":"2026-05-19T18:27:59.257029Z","steps":["trace[1194306185] 'process raft request' (duration: 303.812749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:59.257157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:58.9529Z","time spent":"304.167907ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:27:59.257397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.070728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:59.257488Z","caller":"traceutil/trace.go:171","msg":"trace[42825408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"263.276863ms","start":"2026-05-19T18:27:58.994191Z","end":"2026-05-19T18:27:59.257468Z","steps":["trace[42825408] 'agreement among raft nodes before linearized reading' (duration: 262.860403ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.414191Z","caller":"traceutil/trace.go:171","msg":"trace[1229409671] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1107; }","duration":"157.186272ms","start":"2026-05-19T18:27:59.256979Z","end":"2026-05-19T18:27:59.414165Z","steps":["trace[1229409671] 'read index received' (duration: 156.944436ms)","trace[1229409671] 'applied index is now lower than readState.Index' (duration: 241.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:59.414274Z","caller":"traceutil/trace.go:171","msg":"trace[162170109] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"184.214346ms","start":"2026-05-19T18:27:59.230013Z","end":"2026-05-19T18:27:59.414227Z","steps":["trace[162170109] 'process raft request' (duration: 183.97582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:59.414472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.370279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T18:27:59.41451Z","caller":"traceutil/trace.go:171","msg":"trace[693129201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"161.486891ms","start":"2026-05-19T18:27:59.253015Z","end":"2026-05-19T18:27:59.414501Z","steps":["trace[693129201] 'agreement among raft nodes before linearized reading' (duration: 161.294757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.414457Z","caller":"traceutil/trace.go:171","msg":"trace[978045962] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"161.43294ms","start":"2026-05-19T18:27:59.252965Z","end":"2026-05-19T18:27:59.414398Z","steps":["trace[978045962] 'process raft request' (duration: 161.168173ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.856954Z","caller":"traceutil/trace.go:171","msg":"trace[1807730944] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"205.044312ms","start":"2026-05-19T18:27:59.651876Z","end":"2026-05-19T18:27:59.85692Z","steps":["trace[1807730944] 'process raft request' (duration: 204.853727ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.967257Z","caller":"traceutil/trace.go:171","msg":"trace[683449391] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1111; }","duration":"134.095669ms","start":"2026-05-19T18:27:59.833129Z","end":"2026-05-19T18:27:59.967224Z","steps":["trace[683449391] 'read index received' (duration: 23.574626ms)","trace[683449391] 'applied index is now lower than readState.Index' (duration: 110.514583ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:59.967323Z","caller":"traceutil/trace.go:171","msg":"trace[1909544274] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"315.157904ms","start":"2026-05-19T18:27:59.652135Z","end":"2026-05-19T18:27:59.967293Z","steps":["trace[1909544274] 'process raft request' (duration: 304.644059ms)","trace[1909544274] 'compare' (duration: 10.259708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:59.967391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.243852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:59.967425Z","caller":"traceutil/trace.go:171","msg":"trace[835200883] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1072; }","duration":"134.342035ms","start":"2026-05-19T18:27:59.833073Z","end":"2026-05-19T18:27:59.967415Z","steps":["trace[835200883] 'agreement among raft nodes before linearized reading' (duration: 134.257273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:59.967467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:59.652121Z","time spent":"315.271377ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:01.61356Z","caller":"traceutil/trace.go:171","msg":"trace[1593612317] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"346.021815ms","start":"2026-05-19T18:28:01.267509Z","end":"2026-05-19T18:28:01.613531Z","steps":["trace[1593612317] 'process raft request' (duration: 345.857831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:01.613827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:01.267478Z","time spent":"346.166079ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:02.023263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.969026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:28:02.023377Z","caller":"traceutil/trace.go:171","msg":"trace[1959063247] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"591.583949ms","start":"2026-05-19T18:28:01.431776Z","end":"2026-05-19T18:28:02.02336Z","steps":["trace[1959063247] 'read index received' (duration: 181.616879ms)","trace[1959063247] 'applied index is now lower than readState.Index' (duration: 409.96587ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:02.02342Z","caller":"traceutil/trace.go:171","msg":"trace[890009178] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"602.960218ms","start":"2026-05-19T18:28:01.420415Z","end":"2026-05-19T18:28:02.023375Z","steps":["trace[890009178] 'process raft request' (duration: 299.780566ms)","trace[890009178] 'compare' (duration: 302.29351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:02.024136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:01.420393Z","time spent":"603.654615ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:02.023519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.739344ms","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-19T18:28:02.024275Z","caller":"traceutil/trace.go:171","msg":"trace[57273604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"592.545505ms","start":"2026-05-19T18:28:01.431705Z","end":"2026-05-19T18:28:02.02425Z","steps":["trace[57273604] 'agreement among raft nodes before linearized reading' (duration: 591.702953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:02.024368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:01.43168Z","time spent":"592.657368ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:02.023685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.779076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T18:28:02.024468Z","caller":"traceutil/trace.go:171","msg":"trace[2110178366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"581.594168ms","start":"2026-05-19T18:28:01.442864Z","end":"2026-05-19T18:28:02.024459Z","steps":["trace[2110178366] 'agreement among raft nodes before linearized reading' (duration: 580.694634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:02.0245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:01.442848Z","time spent":"581.642449ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:02.02371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.741797ms","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-19T18:28:02.024581Z","caller":"traceutil/trace.go:171","msg":"trace[626577707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"162.675281ms","start":"2026-05-19T18:28:01.861896Z","end":"2026-05-19T18:28:02.024571Z","steps":["trace[626577707] 'agreement among raft nodes before linearized reading' (duration: 161.774727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:02.023736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.89945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:02.024656Z","caller":"traceutil/trace.go:171","msg":"trace[2119612402] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1074; }","duration":"215.821004ms","start":"2026-05-19T18:28:01.808826Z","end":"2026-05-19T18:28:02.024647Z","steps":["trace[2119612402] 'agreement among raft nodes before linearized reading' (duration: 214.88189ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:02.48562Z","caller":"traceutil/trace.go:171","msg":"trace[575680168] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"457.031689ms","start":"2026-05-19T18:28:02.02855Z","end":"2026-05-19T18:28:02.485582Z","steps":["trace[575680168] 'process raft request' (duration: 397.531176ms)","trace[575680168] 'compare' (duration: 58.951479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:02.485959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:02.028527Z","time spent":"457.183273ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:02.773045Z","caller":"traceutil/trace.go:171","msg":"trace[1733787286] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1116; }","duration":"180.881601ms","start":"2026-05-19T18:28:02.592099Z","end":"2026-05-19T18:28:02.772981Z","steps":["trace[1733787286] 'read index received' (duration: 179.567538ms)","trace[1733787286] 'applied index is now lower than readState.Index' (duration: 1.312673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:02.773205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.078556ms","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-19T18:28:02.773252Z","caller":"traceutil/trace.go:171","msg":"trace[322965432] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1078; }","duration":"181.193099ms","start":"2026-05-19T18:28:02.592044Z","end":"2026-05-19T18:28:02.773237Z","steps":["trace[322965432] 'agreement among raft nodes before linearized reading' (duration: 181.084636ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:02.773234Z","caller":"traceutil/trace.go:171","msg":"trace[1808705296] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"744.433093ms","start":"2026-05-19T18:28:02.028765Z","end":"2026-05-19T18:28:02.773198Z","steps":["trace[1808705296] 'process raft request' (duration: 742.924574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:02.773292Z","caller":"traceutil/trace.go:171","msg":"trace[1600037153] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"744.33705ms","start":"2026-05-19T18:28:02.028916Z","end":"2026-05-19T18:28:02.773253Z","steps":["trace[1600037153] 'process raft request' (duration: 744.019102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:02.773267Z","caller":"traceutil/trace.go:171","msg":"trace[1539898536] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"744.32381ms","start":"2026-05-19T18:28:02.028906Z","end":"2026-05-19T18:28:02.77323Z","steps":["trace[1539898536] 'process raft request' (duration: 743.974821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:02.773478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:02.028756Z","time spent":"744.573356ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:02.773511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:02.028901Z","time spent":"744.527306ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:02.773561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:02.028895Z","time spent":"744.548466ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:08.395115Z","caller":"traceutil/trace.go:171","msg":"trace[788523233] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"261.839158ms","start":"2026-05-19T18:28:08.133235Z","end":"2026-05-19T18:28:08.395074Z","steps":["trace[788523233] 'process raft request' (duration: 181.019835ms)","trace[788523233] 'compare' (duration: 80.665099ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:15.030388Z","caller":"traceutil/trace.go:171","msg":"trace[597642226] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"170.155259ms","start":"2026-05-19T18:28:14.860208Z","end":"2026-05-19T18:28:15.030364Z","steps":["trace[597642226] 'read index received' (duration: 169.967194ms)","trace[597642226] 'applied index is now lower than readState.Index' (duration: 186.835µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:15.030678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.465557ms","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-19T18:28:15.030725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.433608ms","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-19T18:28:15.03078Z","caller":"traceutil/trace.go:171","msg":"trace[1177457738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1167; }","duration":"170.5362ms","start":"2026-05-19T18:28:14.86023Z","end":"2026-05-19T18:28:15.030766Z","steps":["trace[1177457738] 'agreement among raft nodes before linearized reading' (duration: 170.411116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:15.03085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.434057ms","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-19T18:28:15.03089Z","caller":"traceutil/trace.go:171","msg":"trace[268036700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1167; }","duration":"170.489239ms","start":"2026-05-19T18:28:14.860395Z","end":"2026-05-19T18:28:15.030884Z","steps":["trace[268036700] 'agreement among raft nodes before linearized reading' (duration: 170.387706ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:15.03074Z","caller":"traceutil/trace.go:171","msg":"trace[841458798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1167; }","duration":"170.580011ms","start":"2026-05-19T18:28:14.86015Z","end":"2026-05-19T18:28:15.03073Z","steps":["trace[841458798] 'agreement among raft nodes before linearized reading' (duration: 170.409676ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:15.030475Z","caller":"traceutil/trace.go:171","msg":"trace[83988880] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"329.716042ms","start":"2026-05-19T18:28:14.700721Z","end":"2026-05-19T18:28:15.030437Z","steps":["trace[83988880] 'process raft request' (duration: 329.482376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:15.031072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:14.7007Z","time spent":"330.322337ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:19.38068Z","caller":"traceutil/trace.go:171","msg":"trace[1347689311] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"331.488507ms","start":"2026-05-19T18:28:19.04917Z","end":"2026-05-19T18:28:19.380658Z","steps":["trace[1347689311] 'process raft request' (duration: 331.292552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:19.380642Z","caller":"traceutil/trace.go:171","msg":"trace[1321493342] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"319.612507ms","start":"2026-05-19T18:28:19.060994Z","end":"2026-05-19T18:28:19.380607Z","steps":["trace[1321493342] 'read index received' (duration: 319.411162ms)","trace[1321493342] 'applied index is now lower than readState.Index' (duration: 200.495µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:19.380848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.049155Z","time spent":"331.590209ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:19.381104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.539557ms","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-19T18:28:19.381106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.095879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-19T18:28:19.381153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.093834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T18:28:19.38118Z","caller":"traceutil/trace.go:171","msg":"trace[346454056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"312.658701ms","start":"2026-05-19T18:28:19.068503Z","end":"2026-05-19T18:28:19.381162Z","steps":["trace[346454056] 'agreement among raft nodes before linearized reading' (duration: 312.406994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:19.381218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.068489Z","time spent":"312.722612ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T18:28:19.381219Z","caller":"traceutil/trace.go:171","msg":"trace[557911195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"304.180787ms","start":"2026-05-19T18:28:19.077019Z","end":"2026-05-19T18:28:19.381199Z","steps":["trace[557911195] 'agreement among raft nodes before linearized reading' (duration: 303.91724ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:19.381224Z","caller":"traceutil/trace.go:171","msg":"trace[15550639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1180; }","duration":"320.220242ms","start":"2026-05-19T18:28:19.060949Z","end":"2026-05-19T18:28:19.381169Z","steps":["trace[15550639] 'agreement among raft nodes before linearized reading' (duration: 319.927495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:19.381267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.077006Z","time spent":"304.249869ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:19.381313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.060931Z","time spent":"320.366487ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:28:20.074856Z","caller":"traceutil/trace.go:171","msg":"trace[914662376] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"341.015968ms","start":"2026-05-19T18:28:19.733818Z","end":"2026-05-19T18:28:20.074834Z","steps":["trace[914662376] 'read index received' (duration: 340.837034ms)","trace[914662376] 'applied index is now lower than readState.Index' (duration: 175.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:20.075045Z","caller":"traceutil/trace.go:171","msg":"trace[1330154730] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"476.704807ms","start":"2026-05-19T18:28:19.598332Z","end":"2026-05-19T18:28:20.075036Z","steps":["trace[1330154730] 'process raft request' (duration: 476.374609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.075124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.598301Z","time spent":"476.761278ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:20.075296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.477249ms","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-19T18:28:20.075317Z","caller":"traceutil/trace.go:171","msg":"trace[2066984716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"341.540271ms","start":"2026-05-19T18:28:19.733768Z","end":"2026-05-19T18:28:20.075308Z","steps":["trace[2066984716] 'agreement among raft nodes before linearized reading' (duration: 341.459659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.075343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.733744Z","time spent":"341.595303ms","remote":"127.0.0.1:45870","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-19T18:28:21.911424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11579210121048818513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:28:22.398719Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007795488s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T18:28:22.399076Z","caller":"traceutil/trace.go:171","msg":"trace[284181950] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"988.438539ms","start":"2026-05-19T18:28:21.410613Z","end":"2026-05-19T18:28:22.399052Z","steps":["trace[284181950] 'read index received' (duration: 988.294965ms)","trace[284181950] 'applied index is now lower than readState.Index' (duration: 142.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:22.399161Z","caller":"traceutil/trace.go:171","msg":"trace[2095661586] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"1.008317931s","start":"2026-05-19T18:28:21.390824Z","end":"2026-05-19T18:28:22.399142Z","steps":["trace[2095661586] 'process raft request' (duration: 1.008085425s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.399277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.663365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-19T18:28:22.399283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.390803Z","time spent":"1.008399884s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:22.399346Z","caller":"traceutil/trace.go:171","msg":"trace[1271707173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"988.798798ms","start":"2026-05-19T18:28:21.410521Z","end":"2026-05-19T18:28:22.39932Z","steps":["trace[1271707173] 'agreement among raft nodes before linearized reading' (duration: 988.627754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.399388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.410487Z","time spent":"988.89269ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:28:22.418827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.407131ms","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-19T18:28:22.418868Z","caller":"traceutil/trace.go:171","msg":"trace[33189295] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"259.259942ms","start":"2026-05-19T18:28:22.159586Z","end":"2026-05-19T18:28:22.418846Z","steps":["trace[33189295] 'process raft request' (duration: 259.060047ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:22.418902Z","caller":"traceutil/trace.go:171","msg":"trace[1728195399] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1188; }","duration":"996.498863ms","start":"2026-05-19T18:28:21.422387Z","end":"2026-05-19T18:28:22.418886Z","steps":["trace[1728195399] 'agreement among raft nodes before linearized reading' (duration: 996.36554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.419081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.422354Z","time spent":"996.707258ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:28:22.418929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.544413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:28:22.419088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.970194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:22.419226Z","caller":"traceutil/trace.go:171","msg":"trace[844412684] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1188; }","duration":"551.19484ms","start":"2026-05-19T18:28:21.868006Z","end":"2026-05-19T18:28:22.419201Z","steps":["trace[844412684] 'agreement among raft nodes before linearized reading' (duration: 550.943194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:22.419166Z","caller":"traceutil/trace.go:171","msg":"trace[715499285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"423.84291ms","start":"2026-05-19T18:28:21.995304Z","end":"2026-05-19T18:28:22.419147Z","steps":["trace[715499285] 'agreement among raft nodes before linearized reading' (duration: 423.513712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.419254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.502876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T18:28:22.41931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.995283Z","time spent":"424.012715ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:28:22.419306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.867979Z","time spent":"551.306112ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:28:22.419089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.299841ms","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-19T18:28:22.41941Z","caller":"traceutil/trace.go:171","msg":"trace[417710429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"987.68503ms","start":"2026-05-19T18:28:21.431717Z","end":"2026-05-19T18:28:22.419402Z","steps":["trace[417710429] 'agreement among raft nodes before linearized reading' (duration: 987.243699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.419453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.431707Z","time spent":"987.737232ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T18:28:22.419535Z","caller":"traceutil/trace.go:171","msg":"trace[2017274858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"987.621338ms","start":"2026-05-19T18:28:21.431716Z","end":"2026-05-19T18:28:22.419338Z","steps":["trace[2017274858] 'agreement among raft nodes before linearized reading' (duration: 987.131726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.419691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:21.431703Z","time spent":"987.867035ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:22.419722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.633165ms","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-19T18:28:22.419771Z","caller":"traceutil/trace.go:171","msg":"trace[2104662299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1188; }","duration":"330.724008ms","start":"2026-05-19T18:28:22.089035Z","end":"2026-05-19T18:28:22.419759Z","steps":["trace[2104662299] 'agreement among raft nodes before linearized reading' (duration: 329.866486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:22.419819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:22.089014Z","time spent":"330.797619ms","remote":"127.0.0.1:45870","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-19T18:28:36.918215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.259526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:28:36.918521Z","caller":"traceutil/trace.go:171","msg":"trace[211106548] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"289.741063ms","start":"2026-05-19T18:28:36.628756Z","end":"2026-05-19T18:28:36.918497Z","steps":["trace[211106548] 'process raft request' (duration: 289.662151ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:36.918641Z","caller":"traceutil/trace.go:171","msg":"trace[2088591897] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"289.893637ms","start":"2026-05-19T18:28:36.628715Z","end":"2026-05-19T18:28:36.918609Z","steps":["trace[2088591897] 'process raft request' (duration: 33.063486ms)","trace[2088591897] 'compare' (duration: 255.784294ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:36.918807Z","caller":"traceutil/trace.go:171","msg":"trace[1120114959] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"290.06645ms","start":"2026-05-19T18:28:36.628728Z","end":"2026-05-19T18:28:36.918794Z","steps":["trace[1120114959] 'process raft request' (duration: 289.607049ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:36.918897Z","caller":"traceutil/trace.go:171","msg":"trace[64835397] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1298; }","duration":"157.697245ms","start":"2026-05-19T18:28:36.761189Z","end":"2026-05-19T18:28:36.918886Z","steps":["trace[64835397] 'read index received' (duration: 148.100692ms)","trace[64835397] 'applied index is now lower than readState.Index' (duration: 9.595713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:36.919062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.864669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-19T18:28:36.919098Z","caller":"traceutil/trace.go:171","msg":"trace[1827359181] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1252; }","duration":"157.955492ms","start":"2026-05-19T18:28:36.761133Z","end":"2026-05-19T18:28:36.919089Z","steps":["trace[1827359181] 'agreement among raft nodes before linearized reading' (duration: 157.795078ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:36.919357Z","caller":"traceutil/trace.go:171","msg":"trace[335073920] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"289.28509ms","start":"2026-05-19T18:28:36.630061Z","end":"2026-05-19T18:28:36.919346Z","steps":["trace[335073920] 'process raft request' (duration: 288.390158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:38.709416Z","caller":"traceutil/trace.go:171","msg":"trace[943725037] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"112.964994ms","start":"2026-05-19T18:28:38.59641Z","end":"2026-05-19T18:28:38.709375Z","steps":["trace[943725037] 'process raft request' (duration: 112.629696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:39.006371Z","caller":"traceutil/trace.go:171","msg":"trace[2108058065] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"292.546713ms","start":"2026-05-19T18:28:38.713781Z","end":"2026-05-19T18:28:39.006328Z","steps":["trace[2108058065] 'process raft request' (duration: 244.16649ms)","trace[2108058065] 'compare' (duration: 47.965602ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:41.219206Z","caller":"traceutil/trace.go:171","msg":"trace[1908055572] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"164.953969ms","start":"2026-05-19T18:28:41.054228Z","end":"2026-05-19T18:28:41.219182Z","steps":["trace[1908055572] 'read index received' (duration: 164.822066ms)","trace[1908055572] 'applied index is now lower than readState.Index' (duration: 130.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:41.219337Z","caller":"traceutil/trace.go:171","msg":"trace[1794077406] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"203.453432ms","start":"2026-05-19T18:28:41.015849Z","end":"2026-05-19T18:28:41.219302Z","steps":["trace[1794077406] 'process raft request' (duration: 203.160385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.219453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.202815ms","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-19T18:28:41.219496Z","caller":"traceutil/trace.go:171","msg":"trace[678229106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"165.306917ms","start":"2026-05-19T18:28:41.054178Z","end":"2026-05-19T18:28:41.219484Z","steps":["trace[678229106] 'agreement among raft nodes before linearized reading' (duration: 165.134963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.790139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.244409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:28:41.790542Z","caller":"traceutil/trace.go:171","msg":"trace[109899430] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"718.941649ms","start":"2026-05-19T18:28:41.071574Z","end":"2026-05-19T18:28:41.790515Z","steps":["trace[109899430] 'process raft request' (duration: 486.217848ms)","trace[109899430] 'compare' (duration: 231.847529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:41.790666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.004613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:41.790735Z","caller":"traceutil/trace.go:171","msg":"trace[69677572] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1277; }","duration":"638.133746ms","start":"2026-05-19T18:28:41.152586Z","end":"2026-05-19T18:28:41.79072Z","steps":["trace[69677572] 'agreement among raft nodes before linearized reading' (duration: 637.916461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.790779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.152561Z","time spent":"638.203958ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T18:28:41.790775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.914832ms","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-19T18:28:41.790854Z","caller":"traceutil/trace.go:171","msg":"trace[2105105959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"735.031535ms","start":"2026-05-19T18:28:41.055804Z","end":"2026-05-19T18:28:41.790836Z","steps":["trace[2105105959] 'agreement among raft nodes before linearized reading' (duration: 734.730278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.790756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.071522Z","time spent":"719.094883ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:28:41.790897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.055793Z","time spent":"735.098407ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:41.791085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.130412ms","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-19T18:28:41.791201Z","caller":"traceutil/trace.go:171","msg":"trace[1075653020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"734.267136ms","start":"2026-05-19T18:28:41.056913Z","end":"2026-05-19T18:28:41.79118Z","steps":["trace[1075653020] 'agreement among raft nodes before linearized reading' (duration: 733.602979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.791283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.037891ms","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-19T18:28:41.791415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.0569Z","time spent":"734.496891ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:28:41.791376Z","caller":"traceutil/trace.go:171","msg":"trace[1486147212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"736.175084ms","start":"2026-05-19T18:28:41.055187Z","end":"2026-05-19T18:28:41.791363Z","steps":["trace[1486147212] 'agreement among raft nodes before linearized reading' (duration: 735.831846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.791574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.055166Z","time spent":"736.396399ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T18:28:41.791665Z","caller":"traceutil/trace.go:171","msg":"trace[422097810] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"571.071592ms","start":"2026-05-19T18:28:41.219284Z","end":"2026-05-19T18:28:41.790356Z","steps":["trace[422097810] 'read index received' (duration: 338.580527ms)","trace[422097810] 'applied index is now lower than readState.Index' (duration: 232.488045ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:41.803459Z","caller":"traceutil/trace.go:171","msg":"trace[204117390] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"579.128564ms","start":"2026-05-19T18:28:41.224316Z","end":"2026-05-19T18:28:41.803445Z","steps":["trace[204117390] 'process raft request' (duration: 579.023922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.803482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.994668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:41.803604Z","caller":"traceutil/trace.go:171","msg":"trace[1293525304] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1278; }","duration":"474.171864ms","start":"2026-05-19T18:28:41.32942Z","end":"2026-05-19T18:28:41.803592Z","steps":["trace[1293525304] 'agreement among raft nodes before linearized reading' (duration: 474.010769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:41.803651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.329394Z","time spent":"474.247795ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:28:41.803556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.224293Z","time spent":"579.210098ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:42.158307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.939562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:28:42.15868Z","caller":"traceutil/trace.go:171","msg":"trace[53062789] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"363.679701ms","start":"2026-05-19T18:28:41.794957Z","end":"2026-05-19T18:28:42.158637Z","steps":["trace[53062789] 'process raft request' (duration: 260.320008ms)","trace[53062789] 'compare' (duration: 102.670825ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:42.158761Z","caller":"traceutil/trace.go:171","msg":"trace[71611184] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"362.966722ms","start":"2026-05-19T18:28:41.795773Z","end":"2026-05-19T18:28:42.158739Z","steps":["trace[71611184] 'process raft request' (duration: 362.87824ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:42.158899Z","caller":"traceutil/trace.go:171","msg":"trace[17277715] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"363.32034ms","start":"2026-05-19T18:28:41.795548Z","end":"2026-05-19T18:28:42.158869Z","steps":["trace[17277715] 'process raft request' (duration: 362.864269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:42.158929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.794934Z","time spent":"363.845295ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:42.158931Z","caller":"traceutil/trace.go:171","msg":"trace[1922050873] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1329; }","duration":"163.924522ms","start":"2026-05-19T18:28:41.994982Z","end":"2026-05-19T18:28:42.158906Z","steps":["trace[1922050873] 'read index received' (duration: 60.309604ms)","trace[1922050873] 'applied index is now lower than readState.Index' (duration: 103.611988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:42.158984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.795538Z","time spent":"363.410363ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:28:42.158951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.795763Z","time spent":"363.139286ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:42.159112Z","caller":"traceutil/trace.go:171","msg":"trace[701670361] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"363.144697ms","start":"2026-05-19T18:28:41.7959Z","end":"2026-05-19T18:28:42.159045Z","steps":["trace[701670361] 'process raft request' (duration: 362.803938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:42.15921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.795895Z","time spent":"363.26505ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:42.159156Z","caller":"traceutil/trace.go:171","msg":"trace[1975790199] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"363.168727ms","start":"2026-05-19T18:28:41.79595Z","end":"2026-05-19T18:28:42.159119Z","steps":["trace[1975790199] 'process raft request' (duration: 362.873869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:42.159289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.299912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:42.159331Z","caller":"traceutil/trace.go:171","msg":"trace[1332323774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"164.398535ms","start":"2026-05-19T18:28:41.994922Z","end":"2026-05-19T18:28:42.15932Z","steps":["trace[1332323774] 'agreement among raft nodes before linearized reading' (duration: 164.315222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:42.15934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:41.795932Z","time spent":"363.338402ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:28:47.156174Z","caller":"traceutil/trace.go:171","msg":"trace[1679409345] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"161.161273ms","start":"2026-05-19T18:28:46.994985Z","end":"2026-05-19T18:28:47.156146Z","steps":["trace[1679409345] 'read index received' (duration: 160.833564ms)","trace[1679409345] 'applied index is now lower than readState.Index' (duration: 326.549µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:47.156283Z","caller":"traceutil/trace.go:171","msg":"trace[403393553] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"350.246541ms","start":"2026-05-19T18:28:46.805987Z","end":"2026-05-19T18:28:47.156233Z","steps":["trace[403393553] 'process raft request' (duration: 349.971754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:47.156374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.322136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:28:47.156495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:46.805956Z","time spent":"350.439676ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:47.156454Z","caller":"traceutil/trace.go:171","msg":"trace[933007483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"161.48835ms","start":"2026-05-19T18:28:46.994922Z","end":"2026-05-19T18:28:47.156411Z","steps":["trace[933007483] 'agreement among raft nodes before linearized reading' (duration: 161.344057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:47.641188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.576096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-19T18:28:47.6413Z","caller":"traceutil/trace.go:171","msg":"trace[69266213] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1327; }","duration":"304.75009ms","start":"2026-05-19T18:28:47.336524Z","end":"2026-05-19T18:28:47.641274Z","steps":["trace[69266213] 'range keys from in-memory index tree' (duration: 304.362571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:47.641635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.958943ms","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-19T18:28:47.641672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:47.336504Z","time spent":"305.147272ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-19T18:28:47.641459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.630391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T18:28:47.641793Z","caller":"traceutil/trace.go:171","msg":"trace[1765274420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"401.462776ms","start":"2026-05-19T18:28:47.240306Z","end":"2026-05-19T18:28:47.641769Z","steps":["trace[1765274420] 'range keys from in-memory index tree' (duration: 400.785599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:47.641854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:47.240295Z","time spent":"401.542378ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T18:28:47.64187Z","caller":"traceutil/trace.go:171","msg":"trace[761533110] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1327; }","duration":"408.036812ms","start":"2026-05-19T18:28:47.233792Z","end":"2026-05-19T18:28:47.641829Z","steps":["trace[761533110] 'range keys from in-memory index tree' (duration: 407.444876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:47.641954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:47.233737Z","time spent":"408.192315ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T18:29:01.914884Z","caller":"traceutil/trace.go:171","msg":"trace[1699285486] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"182.340339ms","start":"2026-05-19T18:29:01.732486Z","end":"2026-05-19T18:29:01.914827Z","steps":["trace[1699285486] 'process raft request' (duration: 181.689341ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:01.923361Z","caller":"traceutil/trace.go:171","msg":"trace[883200817] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"190.047223ms","start":"2026-05-19T18:29:01.733268Z","end":"2026-05-19T18:29:01.923315Z","steps":["trace[883200817] 'process raft request' (duration: 189.736565ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:40.591323Z","caller":"traceutil/trace.go:171","msg":"trace[1515994063] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"128.436905ms","start":"2026-05-19T18:29:40.462849Z","end":"2026-05-19T18:29:40.591286Z","steps":["trace[1515994063] 'process raft request' (duration: 99.630254ms)","trace[1515994063] 'compare' (duration: 28.615056ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:08.479728Z","caller":"traceutil/trace.go:171","msg":"trace[2024534653] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"148.463297ms","start":"2026-05-19T18:30:08.331236Z","end":"2026-05-19T18:30:08.479699Z","steps":["trace[2024534653] 'process raft request' (duration: 148.275833ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:35.08954Z","caller":"traceutil/trace.go:171","msg":"trace[1328429256] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"115.160916ms","start":"2026-05-19T18:31:34.97436Z","end":"2026-05-19T18:31:35.089521Z","steps":["trace[1328429256] 'read index received' (duration: 114.92836ms)","trace[1328429256] 'applied index is now lower than readState.Index' (duration: 231.596µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:35.089679Z","caller":"traceutil/trace.go:171","msg":"trace[221193452] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"127.606436ms","start":"2026-05-19T18:31:34.962035Z","end":"2026-05-19T18:31:35.089641Z","steps":["trace[221193452] 'process raft request' (duration: 127.35691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:35.08972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.34236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-19T18:31:35.089769Z","caller":"traceutil/trace.go:171","msg":"trace[901964474] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2097; }","duration":"115.422801ms","start":"2026-05-19T18:31:34.974332Z","end":"2026-05-19T18:31:35.089755Z","steps":["trace[901964474] 'agreement among raft nodes before linearized reading' (duration: 115.275808ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:35.305375Z","caller":"traceutil/trace.go:171","msg":"trace[1817957427] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"138.055065ms","start":"2026-05-19T18:31:35.167297Z","end":"2026-05-19T18:31:35.305352Z","steps":["trace[1817957427] 'read index received' (duration: 77.870097ms)","trace[1817957427] 'applied index is now lower than readState.Index' (duration: 60.183578ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:35.305519Z","caller":"traceutil/trace.go:171","msg":"trace[603714090] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"151.857628ms","start":"2026-05-19T18:31:35.153633Z","end":"2026-05-19T18:31:35.305491Z","steps":["trace[603714090] 'process raft request' (duration: 91.582939ms)","trace[603714090] 'compare' (duration: 60.023683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:31:35.305726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.430754ms","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-19T18:31:35.305765Z","caller":"traceutil/trace.go:171","msg":"trace[279040436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"138.510325ms","start":"2026-05-19T18:31:35.167244Z","end":"2026-05-19T18:31:35.305754Z","steps":["trace[279040436] 'agreement among raft nodes before linearized reading' (duration: 138.371792ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:45.325567Z","caller":"traceutil/trace.go:171","msg":"trace[103756132] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"123.434771ms","start":"2026-05-19T18:31:45.202099Z","end":"2026-05-19T18:31:45.325534Z","steps":["trace[103756132] 'process raft request' (duration: 35.574964ms)","trace[103756132] 'compare' (duration: 87.474787ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:32:01.642922Z","caller":"traceutil/trace.go:171","msg":"trace[1620704506] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"239.377947ms","start":"2026-05-19T18:32:01.403508Z","end":"2026-05-19T18:32:01.642886Z","steps":["trace[1620704506] 'process raft request' (duration: 239.161331ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:01.642871Z","caller":"traceutil/trace.go:171","msg":"trace[1096957501] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2333; }","duration":"214.330894ms","start":"2026-05-19T18:32:01.428511Z","end":"2026-05-19T18:32:01.642842Z","steps":["trace[1096957501] 'read index received' (duration: 214.18502ms)","trace[1096957501] 'applied index is now lower than readState.Index' (duration: 144.524µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:32:01.643278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.560291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T18:32:01.643324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.987852ms","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-19T18:32:01.643338Z","caller":"traceutil/trace.go:171","msg":"trace[441637258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2242; }","duration":"151.676003ms","start":"2026-05-19T18:32:01.491645Z","end":"2026-05-19T18:32:01.643321Z","steps":["trace[441637258] 'agreement among raft nodes before linearized reading' (duration: 151.483119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:32:01.643306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.751474ms","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-19T18:32:01.643368Z","caller":"traceutil/trace.go:171","msg":"trace[1942314408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2242; }","duration":"107.071533ms","start":"2026-05-19T18:32:01.536284Z","end":"2026-05-19T18:32:01.643355Z","steps":["trace[1942314408] 'agreement among raft nodes before linearized reading' (duration: 106.93171ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:01.643421Z","caller":"traceutil/trace.go:171","msg":"trace[1281898770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2242; }","duration":"214.941778ms","start":"2026-05-19T18:32:01.428447Z","end":"2026-05-19T18:32:01.643389Z","steps":["trace[1281898770] 'agreement among raft nodes before linearized reading' (duration: 214.570619ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:07.808628Z","caller":"traceutil/trace.go:171","msg":"trace[1693297107] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"126.832366ms","start":"2026-05-19T18:32:07.681772Z","end":"2026-05-19T18:32:07.808605Z","steps":["trace[1693297107] 'process raft request' (duration: 126.681243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:07.84497Z","caller":"traceutil/trace.go:171","msg":"trace[1022260237] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2366; }","duration":"157.141461ms","start":"2026-05-19T18:32:07.687803Z","end":"2026-05-19T18:32:07.844944Z","steps":["trace[1022260237] 'read index received' (duration: 120.657983ms)","trace[1022260237] 'applied index is now lower than readState.Index' (duration: 36.482168ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:32:07.845008Z","caller":"traceutil/trace.go:171","msg":"trace[425920001] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"161.341814ms","start":"2026-05-19T18:32:07.683636Z","end":"2026-05-19T18:32:07.844978Z","steps":["trace[425920001] 'process raft request' (duration: 161.089847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:32:07.845198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.375626ms","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-19T18:32:07.845239Z","caller":"traceutil/trace.go:171","msg":"trace[1397934054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2276; }","duration":"157.466569ms","start":"2026-05-19T18:32:07.687762Z","end":"2026-05-19T18:32:07.845228Z","steps":["trace[1397934054] 'agreement among raft nodes before linearized reading' (duration: 157.290704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:07.845409Z","caller":"traceutil/trace.go:171","msg":"trace[1582272571] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"161.010566ms","start":"2026-05-19T18:32:07.684371Z","end":"2026-05-19T18:32:07.845381Z","steps":["trace[1582272571] 'process raft request' (duration: 160.519574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:42.21528Z","caller":"traceutil/trace.go:171","msg":"trace[1830154963] linearizableReadLoop","detail":"{readStateIndex:2543; appliedIndex:2542; }","duration":"105.07907ms","start":"2026-05-19T18:32:42.110163Z","end":"2026-05-19T18:32:42.215242Z","steps":["trace[1830154963] 'read index received' (duration: 22.915891ms)","trace[1830154963] 'applied index is now lower than readState.Index' (duration: 82.161429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:32:42.215394Z","caller":"traceutil/trace.go:171","msg":"trace[368136917] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"127.623612ms","start":"2026-05-19T18:32:42.087735Z","end":"2026-05-19T18:32:42.215359Z","steps":["trace[368136917] 'process raft request' (duration: 45.33463ms)","trace[368136917] 'compare' (duration: 81.922313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:32:42.215542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.370987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-czcf4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-19T18:32:42.215687Z","caller":"traceutil/trace.go:171","msg":"trace[1802648676] range","detail":"{range_begin:/registry/pods/kube-system/cilium-czcf4; range_end:; response_count:1; response_revision:2443; }","duration":"105.577082ms","start":"2026-05-19T18:32:42.110094Z","end":"2026-05-19T18:32:42.215671Z","steps":["trace[1802648676] 'agreement among raft nodes before linearized reading' (duration: 105.286605ms)"],"step_count":1}