{"level":"warn","ts":"2026-04-23T16:57:46.732851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:57:46.732967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.204: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.204:2380","--initial-cluster=instance=https://199.19.213.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.204: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-04-23T16:57:46.733056Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:57:46.733064Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.204:2380"]} {"level":"info","ts":"2026-04-23T16:57:46.733079Z","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-04-23T16:57:46.733755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"]} {"level":"info","ts":"2026-04-23T16:57:46.733831Z","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.204:2380"],"listen-peer-urls":["https://199.19.213.204:2380"],"advertise-client-urls":["https://199.19.213.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.204: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-04-23T16:57:46.738013Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.990739ms"} {"level":"info","ts":"2026-04-23T16:57:46.747708Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e62df5d1e8b96ac","cluster-id":"502b8ba4e757e03a"} {"level":"info","ts":"2026-04-23T16:57:46.747955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T16:57:46.748106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became follower at term 0"} {"level":"info","ts":"2026-04-23T16:57:46.748134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e62df5d1e8b96ac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T16:57:46.748163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became follower at term 1"} {"level":"info","ts":"2026-04-23T16:57:46.748251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=(9107086987533981356)"} {"level":"warn","ts":"2026-04-23T16:57:46.758624Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T16:57:46.760191Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T16:57:46.761485Z","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-04-23T16:57:46.765116Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e62df5d1e8b96ac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T16:57:46.765631Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e62df5d1e8b96ac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T16:57:46.765867Z","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-04-23T16:57:46.7659Z","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-04-23T16:57:46.765912Z","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-04-23T16:57:46.766382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=(9107086987533981356)"} {"level":"info","ts":"2026-04-23T16:57:46.766515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502b8ba4e757e03a","local-member-id":"7e62df5d1e8b96ac","added-peer-id":"7e62df5d1e8b96ac","added-peer-peer-urls":["https://199.19.213.204:2380"]} {"level":"info","ts":"2026-04-23T16:57:46.772504Z","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-04-23T16:57:46.772589Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.204:2380"} {"level":"info","ts":"2026-04-23T16:57:46.77262Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.204:2380"} {"level":"info","ts":"2026-04-23T16:57:46.772896Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e62df5d1e8b96ac","initial-advertise-peer-urls":["https://199.19.213.204:2380"],"listen-peer-urls":["https://199.19.213.204:2380"],"advertise-client-urls":["https://199.19.213.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T16:57:46.772947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T16:57:47.349213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T16:57:47.349284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T16:57:47.349313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac received MsgPreVoteResp from 7e62df5d1e8b96ac at term 1"} {"level":"info","ts":"2026-04-23T16:57:47.349331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became candidate at term 2"} {"level":"info","ts":"2026-04-23T16:57:47.349339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac received MsgVoteResp from 7e62df5d1e8b96ac at term 2"} {"level":"info","ts":"2026-04-23T16:57:47.349351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became leader at term 2"} {"level":"info","ts":"2026-04-23T16:57:47.349361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e62df5d1e8b96ac elected leader 7e62df5d1e8b96ac at term 2"} {"level":"info","ts":"2026-04-23T16:57:47.360904Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e62df5d1e8b96ac","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.204:2379]}","request-path":"/0/members/7e62df5d1e8b96ac/attributes","cluster-id":"502b8ba4e757e03a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T16:57:47.361057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:57:47.361483Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:57:47.361662Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:57:47.362685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T16:57:47.362753Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T16:57:47.364998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T16:57:47.365386Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.204:2379"} {"level":"info","ts":"2026-04-23T16:57:47.367947Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502b8ba4e757e03a","local-member-id":"7e62df5d1e8b96ac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:57:47.368185Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:57:47.368224Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-23T16:57:50.267369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354200634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:57:51.237378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.348635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:51.23751Z","caller":"traceutil/trace.go:171","msg":"trace[949429790] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"1.484784289s","start":"2026-04-23T16:57:49.752704Z","end":"2026-04-23T16:57:51.237489Z","steps":["trace[949429790] 'process raft request' (duration: 625.67448ms)","trace[949429790] 'compare' (duration: 858.143125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:51.237607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:49.752689Z","time spent":"1.48486838s","remote":"127.0.0.1:32838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:51.398976Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020510003s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T16:57:51.411282Z","caller":"traceutil/trace.go:171","msg":"trace[1664198029] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:139; }","duration":"1.644769025s","start":"2026-04-23T16:57:49.76649Z","end":"2026-04-23T16:57:51.411259Z","steps":["trace[1664198029] 'read index received' (duration: 611.928535ms)","trace[1664198029] 'applied index is now lower than readState.Index' (duration: 1.03283708s)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:51.411374Z","caller":"traceutil/trace.go:171","msg":"trace[1072461574] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"1.457301751s","start":"2026-04-23T16:57:49.95403Z","end":"2026-04-23T16:57:51.411332Z","steps":["trace[1072461574] 'process raft request' (duration: 1.445155793s)","trace[1072461574] 'compare' (duration: 11.945237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:51.411519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058310039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:51.411551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645000927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:51.411531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.335892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:51.411552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:49.954006Z","time spent":"1.457446441s","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:51.411587Z","caller":"traceutil/trace.go:171","msg":"trace[884855004] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:136; }","duration":"1.05846771s","start":"2026-04-23T16:57:50.353105Z","end":"2026-04-23T16:57:51.411573Z","steps":["trace[884855004] 'agreement among raft nodes before linearized reading' (duration: 1.058290249s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:51.411606Z","caller":"traceutil/trace.go:171","msg":"trace[418347162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"1.645123918s","start":"2026-04-23T16:57:49.766463Z","end":"2026-04-23T16:57:51.411586Z","steps":["trace[418347162] 'agreement among raft nodes before linearized reading' (duration: 1.644929647s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:51.411617Z","caller":"traceutil/trace.go:171","msg":"trace[589901343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"549.444073ms","start":"2026-04-23T16:57:50.86215Z","end":"2026-04-23T16:57:51.411594Z","steps":["trace[589901343] 'agreement among raft nodes before linearized reading' (duration: 549.288052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.411622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:50.35308Z","time spent":"1.05853273s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-23T16:57:51.41164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:50.862138Z","time spent":"549.498184ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:57:51.411645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:49.766451Z","time spent":"1.645187478s","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:57:51.411602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.794905ms","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-04-23T16:57:51.411783Z","caller":"traceutil/trace.go:171","msg":"trace[575317591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:136; }","duration":"629.051435ms","start":"2026-04-23T16:57:50.782712Z","end":"2026-04-23T16:57:51.411764Z","steps":["trace[575317591] 'agreement among raft nodes before linearized reading' (duration: 628.720884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.411786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.577762ms","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-04-23T16:57:51.411828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:50.782675Z","time spent":"629.142516ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T16:57:51.411885Z","caller":"traceutil/trace.go:171","msg":"trace[1057210327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:136; }","duration":"630.753054ms","start":"2026-04-23T16:57:50.781104Z","end":"2026-04-23T16:57:51.411857Z","steps":["trace[1057210327] 'agreement among raft nodes before linearized reading' (duration: 630.477783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.411884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.588276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:51.41193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:50.781078Z","time spent":"630.842894ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T16:57:51.412029Z","caller":"traceutil/trace.go:171","msg":"trace[295810024] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:136; }","duration":"170.799817ms","start":"2026-04-23T16:57:51.241178Z","end":"2026-04-23T16:57:51.411978Z","steps":["trace[295810024] 'agreement among raft nodes before linearized reading' (duration: 170.423846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:52.169135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.902049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:52.169265Z","caller":"traceutil/trace.go:171","msg":"trace[826391594] linearizableReadLoop","detail":"{readStateIndex:200; appliedIndex:199; }","duration":"303.264606ms","start":"2026-04-23T16:57:51.86598Z","end":"2026-04-23T16:57:52.169244Z","steps":["trace[826391594] 'read index received' (duration: 14.126896ms)","trace[826391594] 'applied index is now lower than readState.Index' (duration: 289.13634ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:52.169339Z","caller":"traceutil/trace.go:171","msg":"trace[2093844296] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"409.448372ms","start":"2026-04-23T16:57:51.75982Z","end":"2026-04-23T16:57:52.169268Z","steps":["trace[2093844296] 'process raft request' (duration: 120.322902ms)","trace[2093844296] 'compare' (duration: 288.708158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:52.169426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.443947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:52.169459Z","caller":"traceutil/trace.go:171","msg":"trace[397779675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:195; }","duration":"303.531647ms","start":"2026-04-23T16:57:51.865915Z","end":"2026-04-23T16:57:52.169447Z","steps":["trace[397779675] 'agreement among raft nodes before linearized reading' (duration: 303.381196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:52.169501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:51.865878Z","time spent":"303.615398ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:57:52.169556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:51.7598Z","time spent":"409.664224ms","remote":"127.0.0.1:32842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":588,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:52.924645Z","caller":"traceutil/trace.go:171","msg":"trace[140556400] linearizableReadLoop","detail":"{readStateIndex:201; appliedIndex:200; }","duration":"458.370609ms","start":"2026-04-23T16:57:52.466241Z","end":"2026-04-23T16:57:52.924611Z","steps":["trace[140556400] 'read index received' (duration: 441.730851ms)","trace[140556400] 'applied index is now lower than readState.Index' (duration: 16.638258ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:52.924741Z","caller":"traceutil/trace.go:171","msg":"trace[726069812] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"747.485458ms","start":"2026-04-23T16:57:52.177235Z","end":"2026-04-23T16:57:52.924721Z","steps":["trace[726069812] 'process raft request' (duration: 730.83833ms)","trace[726069812] 'compare' (duration: 16.340647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:52.924829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.574741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:52.924854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:52.177216Z","time spent":"747.568458ms","remote":"127.0.0.1:32842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:52.92488Z","caller":"traceutil/trace.go:171","msg":"trace[1181619575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:196; }","duration":"458.685181ms","start":"2026-04-23T16:57:52.466178Z","end":"2026-04-23T16:57:52.924863Z","steps":["trace[1181619575] 'agreement among raft nodes before linearized reading' (duration: 458.562971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:52.924925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:52.46615Z","time spent":"458.764222ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:57:53.290756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.266618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:53.290964Z","caller":"traceutil/trace.go:171","msg":"trace[381083721] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"141.222799ms","start":"2026-04-23T16:57:53.149721Z","end":"2026-04-23T16:57:53.290943Z","steps":["trace[381083721] 'process raft request' (duration: 141.158178ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:53.291052Z","caller":"traceutil/trace.go:171","msg":"trace[398298176] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"200.312016ms","start":"2026-04-23T16:57:53.09069Z","end":"2026-04-23T16:57:53.291002Z","steps":["trace[398298176] 'process raft request' (duration: 67.486396ms)","trace[398298176] 'compare' (duration: 132.077576ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:53.471717Z","caller":"traceutil/trace.go:171","msg":"trace[1053974198] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"160.173558ms","start":"2026-04-23T16:57:53.311502Z","end":"2026-04-23T16:57:53.471675Z","steps":["trace[1053974198] 'process raft request' (duration: 120.052811ms)","trace[1053974198] 'compare' (duration: 39.941096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:54.026509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:54.026652Z","caller":"traceutil/trace.go:171","msg":"trace[1583846401] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"501.33373ms","start":"2026-04-23T16:57:53.525294Z","end":"2026-04-23T16:57:54.026628Z","steps":["trace[1583846401] 'process raft request' (duration: 387.089977ms)","trace[1583846401] 'compare' (duration: 113.883942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:54.026713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:53.525278Z","time spent":"501.411361ms","remote":"127.0.0.1:32842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:54.318006Z","caller":"traceutil/trace.go:171","msg":"trace[1668810283] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"237.338878ms","start":"2026-04-23T16:57:54.080639Z","end":"2026-04-23T16:57:54.317978Z","steps":["trace[1668810283] 'read index received' (duration: 158.262809ms)","trace[1668810283] 'applied index is now lower than readState.Index' (duration: 79.074779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:54.318165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.496978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:54.318202Z","caller":"traceutil/trace.go:171","msg":"trace[1373614399] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:0; response_revision:217; }","duration":"237.582449ms","start":"2026-04-23T16:57:54.080606Z","end":"2026-04-23T16:57:54.318189Z","steps":["trace[1373614399] 'agreement among raft nodes before linearized reading' (duration: 237.480759ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:54.318399Z","caller":"traceutil/trace.go:171","msg":"trace[1205353027] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"238.374202ms","start":"2026-04-23T16:57:54.079976Z","end":"2026-04-23T16:57:54.318351Z","steps":["trace[1205353027] 'process raft request' (duration: 159.018003ms)","trace[1205353027] 'compare' (duration: 78.874497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:54.67043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.177993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:54.670563Z","caller":"traceutil/trace.go:171","msg":"trace[2042468051] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:226; }","duration":"203.44232ms","start":"2026-04-23T16:57:54.467087Z","end":"2026-04-23T16:57:54.670529Z","steps":["trace[2042468051] 'read index received' (duration: 42.62µs)","trace[2042468051] 'applied index is now lower than readState.Index' (duration: 203.39841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:54.670682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.59393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:54.670648Z","caller":"traceutil/trace.go:171","msg":"trace[1515417280] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"264.729336ms","start":"2026-04-23T16:57:54.405866Z","end":"2026-04-23T16:57:54.670596Z","steps":["trace[1515417280] 'process raft request' (duration: 13.303482ms)","trace[1515417280] 'compare' (duration: 250.971432ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:54.670714Z","caller":"traceutil/trace.go:171","msg":"trace[757392160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:222; }","duration":"203.664011ms","start":"2026-04-23T16:57:54.467041Z","end":"2026-04-23T16:57:54.670705Z","steps":["trace[757392160] 'agreement among raft nodes before linearized reading' (duration: 203.557531ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.741273Z","caller":"traceutil/trace.go:171","msg":"trace[1415680216] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"390.205761ms","start":"2026-04-23T16:58:18.351049Z","end":"2026-04-23T16:58:18.741255Z","steps":["trace[1415680216] 'process raft request' (duration: 390.112141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.741573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.351032Z","time spent":"390.502683ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.748633Z","caller":"traceutil/trace.go:171","msg":"trace[1986440954] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"389.98356ms","start":"2026-04-23T16:58:18.358623Z","end":"2026-04-23T16:58:18.748607Z","steps":["trace[1986440954] 'process raft request' (duration: 389.771699ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.748792Z","caller":"traceutil/trace.go:171","msg":"trace[1507904963] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"386.600944ms","start":"2026-04-23T16:58:18.362179Z","end":"2026-04-23T16:58:18.74878Z","steps":["trace[1507904963] 'process raft request' (duration: 386.440994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.748845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.36217Z","time spent":"386.647234ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:18.74885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.358599Z","time spent":"390.10732ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.748749Z","caller":"traceutil/trace.go:171","msg":"trace[736398064] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"389.908749ms","start":"2026-04-23T16:58:18.358786Z","end":"2026-04-23T16:58:18.748695Z","steps":["trace[736398064] 'process raft request' (duration: 389.698698ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.749028Z","caller":"traceutil/trace.go:171","msg":"trace[243168482] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"384.035612ms","start":"2026-04-23T16:58:18.364987Z","end":"2026-04-23T16:58:18.749023Z","steps":["trace[243168482] 'process raft request' (duration: 383.759521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.35877Z","time spent":"390.18202ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:18.749059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.364971Z","time spent":"384.075532ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.749179Z","caller":"traceutil/trace.go:171","msg":"trace[1849530145] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"383.988342ms","start":"2026-04-23T16:58:18.365185Z","end":"2026-04-23T16:58:18.749174Z","steps":["trace[1849530145] 'process raft request' (duration: 383.787371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.365177Z","time spent":"384.019022ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.749228Z","caller":"traceutil/trace.go:171","msg":"trace[374718483] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"379.11608ms","start":"2026-04-23T16:58:18.370098Z","end":"2026-04-23T16:58:18.749214Z","steps":["trace[374718483] 'process raft request' (duration: 378.903458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.37008Z","time spent":"379.18607ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.74937Z","caller":"traceutil/trace.go:171","msg":"trace[346852912] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"371.634726ms","start":"2026-04-23T16:58:18.37773Z","end":"2026-04-23T16:58:18.749365Z","steps":["trace[346852912] 'process raft request' (duration: 371.413774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.377713Z","time spent":"371.669486ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.749517Z","caller":"traceutil/trace.go:171","msg":"trace[1813443825] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"359.130606ms","start":"2026-04-23T16:58:18.390337Z","end":"2026-04-23T16:58:18.749467Z","steps":["trace[1813443825] 'process raft request' (duration: 358.920005ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.749569Z","caller":"traceutil/trace.go:171","msg":"trace[1230367038] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"357.251787ms","start":"2026-04-23T16:58:18.392311Z","end":"2026-04-23T16:58:18.749563Z","steps":["trace[1230367038] 'process raft request' (duration: 357.036817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.390308Z","time spent":"359.244587ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:18.749596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.392293Z","time spent":"357.291507ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.74982Z","caller":"traceutil/trace.go:171","msg":"trace[1298718581] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"332.971534ms","start":"2026-04-23T16:58:18.416844Z","end":"2026-04-23T16:58:18.749816Z","steps":["trace[1298718581] 'process raft request' (duration: 332.667103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:18.749847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.416815Z","time spent":"333.021034ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:18.750027Z","caller":"traceutil/trace.go:171","msg":"trace[539264021] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"233.091677ms","start":"2026-04-23T16:58:18.51693Z","end":"2026-04-23T16:58:18.750021Z","steps":["trace[539264021] 'process raft request' (duration: 232.868526ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:25.33895Z","caller":"traceutil/trace.go:171","msg":"trace[1922899831] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"394.254934ms","start":"2026-04-23T16:58:24.944658Z","end":"2026-04-23T16:58:25.338913Z","steps":["trace[1922899831] 'read index received' (duration: 393.417963ms)","trace[1922899831] 'applied index is now lower than readState.Index' (duration: 836.091µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:25.339013Z","caller":"traceutil/trace.go:171","msg":"trace[1702765700] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"437.107734ms","start":"2026-04-23T16:58:24.901844Z","end":"2026-04-23T16:58:25.338952Z","steps":["trace[1702765700] 'process raft request' (duration: 436.521957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:25.339137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.464334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nrvxb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-23T16:58:25.339178Z","caller":"traceutil/trace.go:171","msg":"trace[169590664] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nrvxb; range_end:; response_count:1; response_revision:627; }","duration":"394.576675ms","start":"2026-04-23T16:58:24.94459Z","end":"2026-04-23T16:58:25.339167Z","steps":["trace[169590664] 'agreement among raft nodes before linearized reading' (duration: 394.466324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:25.339217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:24.944571Z","time spent":"394.637941ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-nrvxb\" "} {"level":"warn","ts":"2026-04-23T16:58:25.33927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:24.889671Z","time spent":"449.453674ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:58:25.339517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.152976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T16:58:25.339611Z","caller":"traceutil/trace.go:171","msg":"trace[1929011701] range","detail":"{range_begin:/registry/masterleases/199.19.213.204; range_end:; response_count:1; response_revision:627; }","duration":"371.282588ms","start":"2026-04-23T16:58:24.968308Z","end":"2026-04-23T16:58:25.33959Z","steps":["trace[1929011701] 'agreement among raft nodes before linearized reading' (duration: 371.08693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:25.339657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:24.968275Z","time spent":"371.373068ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.204\" "} {"level":"warn","ts":"2026-04-23T16:58:25.339676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.043458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T16:58:25.339712Z","caller":"traceutil/trace.go:171","msg":"trace[50320568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"290.111135ms","start":"2026-04-23T16:58:25.049589Z","end":"2026-04-23T16:58:25.3397Z","steps":["trace[50320568] 'agreement among raft nodes before linearized reading' (duration: 290.047069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:25.339837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.688525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:25.339908Z","caller":"traceutil/trace.go:171","msg":"trace[1401793108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"347.775392ms","start":"2026-04-23T16:58:24.992099Z","end":"2026-04-23T16:58:25.339874Z","steps":["trace[1401793108] 'agreement among raft nodes before linearized reading' (duration: 347.670362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:25.339943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:24.992089Z","time spent":"347.84705ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:58:25.986391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354201647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T16:58:26.186585Z","caller":"traceutil/trace.go:171","msg":"trace[714707744] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"701.136954ms","start":"2026-04-23T16:58:25.485424Z","end":"2026-04-23T16:58:26.18656Z","steps":["trace[714707744] 'read index received' (duration: 700.98834ms)","trace[714707744] 'applied index is now lower than readState.Index' (duration: 147.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:26.186787Z","caller":"traceutil/trace.go:171","msg":"trace[398059688] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"731.145736ms","start":"2026-04-23T16:58:25.455627Z","end":"2026-04-23T16:58:26.186773Z","steps":["trace[398059688] 'process raft request' (duration: 730.837747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.186884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:25.455617Z","time spent":"731.207852ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:26.18705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.613199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T16:58:26.187059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.288307ms","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-04-23T16:58:26.187117Z","caller":"traceutil/trace.go:171","msg":"trace[659089403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"701.727511ms","start":"2026-04-23T16:58:25.485372Z","end":"2026-04-23T16:58:26.187099Z","steps":["trace[659089403] 'agreement among raft nodes before linearized reading' (duration: 701.476346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.18716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:25.485352Z","time spent":"701.799128ms","remote":"127.0.0.1:60984","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-04-23T16:58:26.187161Z","caller":"traceutil/trace.go:171","msg":"trace[1526561187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"532.449993ms","start":"2026-04-23T16:58:25.654683Z","end":"2026-04-23T16:58:26.187133Z","steps":["trace[1526561187] 'agreement among raft nodes before linearized reading' (duration: 532.178526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.18718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.639521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:26.187216Z","caller":"traceutil/trace.go:171","msg":"trace[861352421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"194.714538ms","start":"2026-04-23T16:58:25.992492Z","end":"2026-04-23T16:58:26.187206Z","steps":["trace[861352421] 'agreement among raft nodes before linearized reading' (duration: 194.650962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.187222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:25.654664Z","time spent":"532.542793ms","remote":"127.0.0.1:60984","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-04-23T16:58:26.187467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.955236ms","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-04-23T16:58:26.187502Z","caller":"traceutil/trace.go:171","msg":"trace[1862264387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"529.004341ms","start":"2026-04-23T16:58:25.658488Z","end":"2026-04-23T16:58:26.187492Z","steps":["trace[1862264387] 'agreement among raft nodes before linearized reading' (duration: 528.878359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.187531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:25.658482Z","time spent":"529.042434ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T16:58:26.592567Z","caller":"traceutil/trace.go:171","msg":"trace[1392204040] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"400.595545ms","start":"2026-04-23T16:58:26.19195Z","end":"2026-04-23T16:58:26.592546Z","steps":["trace[1392204040] 'process raft request' (duration: 400.469233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.592668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.191937Z","time spent":"400.684753ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:26.59268Z","caller":"traceutil/trace.go:171","msg":"trace[2004810547] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"397.424859ms","start":"2026-04-23T16:58:26.195243Z","end":"2026-04-23T16:58:26.592668Z","steps":["trace[2004810547] 'process raft request' (duration: 397.395196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.592749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.195232Z","time spent":"397.474963ms","remote":"127.0.0.1:60946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:58:26.592872Z","caller":"traceutil/trace.go:171","msg":"trace[940317985] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"399.510459ms","start":"2026-04-23T16:58:26.19335Z","end":"2026-04-23T16:58:26.592861Z","steps":["trace[940317985] 'process raft request' (duration: 399.229732ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:26.592887Z","caller":"traceutil/trace.go:171","msg":"trace[969967955] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"400.700104ms","start":"2026-04-23T16:58:26.192161Z","end":"2026-04-23T16:58:26.592861Z","steps":["trace[969967955] 'process raft request' (duration: 400.349561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:26.5929Z","caller":"traceutil/trace.go:171","msg":"trace[897757941] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"400.649489ms","start":"2026-04-23T16:58:26.192235Z","end":"2026-04-23T16:58:26.592885Z","steps":["trace[897757941] 'process raft request' (duration: 400.311317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.592927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.193337Z","time spent":"399.560455ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:58:26.592982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.192221Z","time spent":"400.724627ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:58:26.593005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.192152Z","time spent":"400.786832ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:58:26.593105Z","caller":"traceutil/trace.go:171","msg":"trace[130892496] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"398.669809ms","start":"2026-04-23T16:58:26.194427Z","end":"2026-04-23T16:58:26.593097Z","steps":["trace[130892496] 'process raft request' (duration: 398.182121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:26.593146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:26.19441Z","time spent":"398.713383ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:27.575658Z","caller":"traceutil/trace.go:171","msg":"trace[1387467364] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"154.68575ms","start":"2026-04-23T16:58:27.420953Z","end":"2026-04-23T16:58:27.575639Z","steps":["trace[1387467364] 'process raft request' (duration: 154.57783ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:27.61097Z","caller":"traceutil/trace.go:171","msg":"trace[1103676601] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"138.762166ms","start":"2026-04-23T16:58:27.472181Z","end":"2026-04-23T16:58:27.610943Z","steps":["trace[1103676601] 'process raft request' (duration: 136.225422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:41.986947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.119785ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-23T16:58:41.987052Z","caller":"traceutil/trace.go:171","msg":"trace[933599832] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"995.966597ms","start":"2026-04-23T16:58:40.991066Z","end":"2026-04-23T16:58:41.987032Z","steps":["trace[933599832] 'read index received' (duration: 495.77916ms)","trace[933599832] 'applied index is now lower than readState.Index' (duration: 500.185887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:41.987187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.10827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:41.987214Z","caller":"traceutil/trace.go:171","msg":"trace[948045387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"996.16764ms","start":"2026-04-23T16:58:40.991037Z","end":"2026-04-23T16:58:41.987205Z","steps":["trace[948045387] 'agreement among raft nodes before linearized reading' (duration: 996.108009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:41.987246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:40.991024Z","time spent":"996.213932ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:58:41.987363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.446573ms","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-04-23T16:58:41.987454Z","caller":"traceutil/trace.go:171","msg":"trace[1354335723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"306.578886ms","start":"2026-04-23T16:58:41.680847Z","end":"2026-04-23T16:58:41.987426Z","steps":["trace[1354335723] 'agreement among raft nodes before linearized reading' (duration: 306.336711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:41.987505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:41.680821Z","time spent":"306.671178ms","remote":"127.0.0.1:60984","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-04-23T17:02:01.080288Z","caller":"traceutil/trace.go:171","msg":"trace[1479461923] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"161.418225ms","start":"2026-04-23T17:02:00.91885Z","end":"2026-04-23T17:02:01.080268Z","steps":["trace[1479461923] 'process raft request' (duration: 161.292813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:01.441747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.363991ms","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-04-23T17:02:01.441816Z","caller":"traceutil/trace.go:171","msg":"trace[2079706455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1545; }","duration":"219.470854ms","start":"2026-04-23T17:02:01.222328Z","end":"2026-04-23T17:02:01.441799Z","steps":["trace[2079706455] 'range keys from in-memory index tree' (duration: 219.232739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:01.441914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.307828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-23T17:02:01.441976Z","caller":"traceutil/trace.go:171","msg":"trace[2034149823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1545; }","duration":"202.400689ms","start":"2026-04-23T17:02:01.239562Z","end":"2026-04-23T17:02:01.441962Z","steps":["trace[2034149823] 'range keys from in-memory index tree' (duration: 202.120323ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:01.728557Z","caller":"traceutil/trace.go:171","msg":"trace[1168744115] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"283.191593ms","start":"2026-04-23T17:02:01.445333Z","end":"2026-04-23T17:02:01.728525Z","steps":["trace[1168744115] 'process raft request' (duration: 283.019409ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:01.730023Z","caller":"traceutil/trace.go:171","msg":"trace[347006959] linearizableReadLoop","detail":"{readStateIndex:1616; appliedIndex:1614; }","duration":"100.242866ms","start":"2026-04-23T17:02:01.629755Z","end":"2026-04-23T17:02:01.729998Z","steps":["trace[347006959] 'read index received' (duration: 98.618174ms)","trace[347006959] 'applied index is now lower than readState.Index' (duration: 1.623232ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:01.730123Z","caller":"traceutil/trace.go:171","msg":"trace[1313819336] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"284.538928ms","start":"2026-04-23T17:02:01.445542Z","end":"2026-04-23T17:02:01.730081Z","steps":["trace[1313819336] 'process raft request' (duration: 284.314804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:01.730245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.48432ms","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-04-23T17:02:01.730282Z","caller":"traceutil/trace.go:171","msg":"trace[1458228356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1547; }","duration":"100.599923ms","start":"2026-04-23T17:02:01.629671Z","end":"2026-04-23T17:02:01.730271Z","steps":["trace[1458228356] 'agreement among raft nodes before linearized reading' (duration: 100.45379ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:24.70314Z","caller":"traceutil/trace.go:171","msg":"trace[1408656352] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"101.864526ms","start":"2026-04-23T17:02:24.601242Z","end":"2026-04-23T17:02:24.703107Z","steps":["trace[1408656352] 'process raft request' (duration: 101.707423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:25.259183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.91291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T17:02:25.259324Z","caller":"traceutil/trace.go:171","msg":"trace[1528926611] range","detail":"{range_begin:/registry/masterleases/199.19.213.204; range_end:; response_count:1; response_revision:1624; }","duration":"279.087973ms","start":"2026-04-23T17:02:24.980207Z","end":"2026-04-23T17:02:25.259295Z","steps":["trace[1528926611] 'range keys from in-memory index tree' (duration: 278.669005ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:25.457865Z","caller":"traceutil/trace.go:171","msg":"trace[134973734] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"142.824341ms","start":"2026-04-23T17:02:25.315012Z","end":"2026-04-23T17:02:25.457836Z","steps":["trace[134973734] 'process raft request' (duration: 62.980625ms)","trace[134973734] 'compare' (duration: 79.726703ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:29.788743Z","caller":"traceutil/trace.go:171","msg":"trace[787239800] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1711; }","duration":"369.837642ms","start":"2026-04-23T17:02:29.418865Z","end":"2026-04-23T17:02:29.788702Z","steps":["trace[787239800] 'read index received' (duration: 369.531945ms)","trace[787239800] 'applied index is now lower than readState.Index' (duration: 304.456µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:29.788865Z","caller":"traceutil/trace.go:171","msg":"trace[1703855237] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"394.501826ms","start":"2026-04-23T17:02:29.394315Z","end":"2026-04-23T17:02:29.788816Z","steps":["trace[1703855237] 'process raft request' (duration: 394.105107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:29.788988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.076436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:29.789033Z","caller":"traceutil/trace.go:171","msg":"trace[939457939] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1638; }","duration":"370.199318ms","start":"2026-04-23T17:02:29.41882Z","end":"2026-04-23T17:02:29.789019Z","steps":["trace[939457939] 'agreement among raft nodes before linearized reading' (duration: 370.072826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:29.789075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:29.418806Z","time spent":"370.25929ms","remote":"127.0.0.1:54838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:02:29.789124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.189493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:29.789239Z","caller":"traceutil/trace.go:171","msg":"trace[508785627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1638; }","duration":"283.381166ms","start":"2026-04-23T17:02:29.505826Z","end":"2026-04-23T17:02:29.789207Z","steps":["trace[508785627] 'agreement among raft nodes before linearized reading' (duration: 283.109111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:29.789107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:29.39428Z","time spent":"394.695269ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:02:43.908849Z","caller":"traceutil/trace.go:171","msg":"trace[1590180992] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"910.697194ms","start":"2026-04-23T17:02:42.998089Z","end":"2026-04-23T17:02:43.908786Z","steps":["trace[1590180992] 'process raft request' (duration: 910.230366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:43.90912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:42.998072Z","time spent":"910.909129ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:44.535992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354204369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:02:45.029935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.349532Z","time spent":"680.396269ms","remote":"127.0.0.1:36698","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T17:02:45.030295Z","caller":"traceutil/trace.go:171","msg":"trace[1051683273] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"1.118503321s","start":"2026-04-23T17:02:43.911768Z","end":"2026-04-23T17:02:45.030272Z","steps":["trace[1051683273] 'process raft request' (duration: 1.084362s)","trace[1051683273] 'compare' (duration: 33.451419ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:45.030327Z","caller":"traceutil/trace.go:171","msg":"trace[1753111316] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"1.012662002s","start":"2026-04-23T17:02:44.017641Z","end":"2026-04-23T17:02:45.030303Z","steps":["trace[1753111316] 'process raft request' (duration: 1.01254193s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:45.030412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:43.911721Z","time spent":"1.118626055s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:45.030446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.01761Z","time spent":"1.012759624s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:45.030511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.325371ms","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-04-23T17:02:45.030559Z","caller":"traceutil/trace.go:171","msg":"trace[420971189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1736; }","duration":"995.431932ms","start":"2026-04-23T17:02:44.035113Z","end":"2026-04-23T17:02:45.030545Z","steps":["trace[420971189] 'agreement among raft nodes before linearized reading' (duration: 995.245689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:45.030594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.035087Z","time spent":"995.497184ms","remote":"127.0.0.1:60984","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-04-23T17:02:45.030303Z","caller":"traceutil/trace.go:171","msg":"trace[1710112192] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1813; }","duration":"995.100255ms","start":"2026-04-23T17:02:44.035174Z","end":"2026-04-23T17:02:45.030274Z","steps":["trace[1710112192] 'read index received' (duration: 960.925893ms)","trace[1710112192] 'applied index is now lower than readState.Index' (duration: 34.173492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:02:45.030879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.607334ms","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-04-23T17:02:45.031004Z","caller":"traceutil/trace.go:171","msg":"trace[2002773883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1736; }","duration":"942.688175ms","start":"2026-04-23T17:02:44.088235Z","end":"2026-04-23T17:02:45.030923Z","steps":["trace[2002773883] 'agreement among raft nodes before linearized reading' (duration: 942.532712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:45.031099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.088223Z","time spent":"942.821128ms","remote":"127.0.0.1:60984","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-04-23T17:02:45.031293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.83332ms","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-04-23T17:02:45.031405Z","caller":"traceutil/trace.go:171","msg":"trace[1162766624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1736; }","duration":"930.983453ms","start":"2026-04-23T17:02:44.100396Z","end":"2026-04-23T17:02:45.031379Z","steps":["trace[1162766624] 'agreement among raft nodes before linearized reading' (duration: 930.696107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:45.03141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.147836ms","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-04-23T17:02:45.031456Z","caller":"traceutil/trace.go:171","msg":"trace[1729943393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1736; }","duration":"931.215197ms","start":"2026-04-23T17:02:44.100229Z","end":"2026-04-23T17:02:45.031444Z","steps":["trace[1729943393] 'agreement among raft nodes before linearized reading' (duration: 931.102325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:45.031457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.100386Z","time spent":"931.060374ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:02:45.031485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:44.100218Z","time spent":"931.260547ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T17:03:03.458469Z","caller":"traceutil/trace.go:171","msg":"trace[1039441297] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"126.217459ms","start":"2026-04-23T17:03:03.332226Z","end":"2026-04-23T17:03:03.458444Z","steps":["trace[1039441297] 'process raft request' (duration: 126.079216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:03.459061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.536657ms","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-04-23T17:03:03.459142Z","caller":"traceutil/trace.go:171","msg":"trace[276341172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1838; }","duration":"125.652739ms","start":"2026-04-23T17:03:03.333469Z","end":"2026-04-23T17:03:03.459122Z","steps":["trace[276341172] 'agreement among raft nodes before linearized reading' (duration: 125.415565ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:03.459282Z","caller":"traceutil/trace.go:171","msg":"trace[1369812680] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1921; }","duration":"125.165179ms","start":"2026-04-23T17:03:03.333499Z","end":"2026-04-23T17:03:03.458664Z","steps":["trace[1369812680] 'read index received' (duration: 125.156499ms)","trace[1369812680] 'applied index is now lower than readState.Index' (duration: 6.84µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:03.566404Z","caller":"traceutil/trace.go:171","msg":"trace[973372012] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"104.851232ms","start":"2026-04-23T17:03:03.461533Z","end":"2026-04-23T17:03:03.566385Z","steps":["trace[973372012] 'read index received' (duration: 88.4989ms)","trace[973372012] 'applied index is now lower than readState.Index' (duration: 16.351382ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:03.566574Z","caller":"traceutil/trace.go:171","msg":"trace[581162221] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"231.194214ms","start":"2026-04-23T17:03:03.335359Z","end":"2026-04-23T17:03:03.566553Z","steps":["trace[581162221] 'process raft request' (duration: 214.663049ms)","trace[581162221] 'compare' (duration: 16.25896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:03.566596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.040359ms","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-04-23T17:03:03.566629Z","caller":"traceutil/trace.go:171","msg":"trace[1416057864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1839; }","duration":"233.13947ms","start":"2026-04-23T17:03:03.33348Z","end":"2026-04-23T17:03:03.56662Z","steps":["trace[1416057864] 'agreement among raft nodes before linearized reading' (duration: 232.981047ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:03.570583Z","caller":"traceutil/trace.go:171","msg":"trace[374007633] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"107.439371ms","start":"2026-04-23T17:03:03.462947Z","end":"2026-04-23T17:03:03.570386Z","steps":["trace[374007633] 'process raft request' (duration: 107.308989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.492048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354204613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:03:05.503631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:04.984313Z","time spent":"519.313743ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T17:03:05.932391Z","caller":"traceutil/trace.go:171","msg":"trace[784314018] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"761.567719ms","start":"2026-04-23T17:03:05.170748Z","end":"2026-04-23T17:03:05.932316Z","steps":["trace[784314018] 'process raft request' (duration: 761.273483ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:05.9326Z","caller":"traceutil/trace.go:171","msg":"trace[1967212113] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1932; }","duration":"940.997053ms","start":"2026-04-23T17:03:04.991571Z","end":"2026-04-23T17:03:05.932568Z","steps":["trace[1967212113] 'read index received' (duration: 512.014584ms)","trace[1967212113] 'applied index is now lower than readState.Index' (duration: 428.980329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:05.932725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.170721Z","time spent":"761.839583ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:05.932803Z","caller":"traceutil/trace.go:171","msg":"trace[501547983] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"743.243848ms","start":"2026-04-23T17:03:05.189527Z","end":"2026-04-23T17:03:05.932771Z","steps":["trace[501547983] 'process raft request' (duration: 742.698678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.932941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.189513Z","time spent":"743.337359ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:05.932991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.416182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:05.933057Z","caller":"traceutil/trace.go:171","msg":"trace[809875703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"941.481764ms","start":"2026-04-23T17:03:04.991538Z","end":"2026-04-23T17:03:05.93302Z","steps":["trace[809875703] 'agreement among raft nodes before linearized reading' (duration: 941.381622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.933096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:04.991524Z","time spent":"941.564185ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:03:05.944594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.565704ms","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-04-23T17:03:05.944647Z","caller":"traceutil/trace.go:171","msg":"trace[1582534200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1852; }","duration":"689.661376ms","start":"2026-04-23T17:03:05.254974Z","end":"2026-04-23T17:03:05.944635Z","steps":["trace[1582534200] 'agreement among raft nodes before linearized reading' (duration: 689.511463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.944682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.254955Z","time spent":"689.717587ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:03:05.944672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.513296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T17:03:05.944595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.757507ms","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-04-23T17:03:05.944729Z","caller":"traceutil/trace.go:171","msg":"trace[399983644] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1852; }","duration":"906.678998ms","start":"2026-04-23T17:03:05.038037Z","end":"2026-04-23T17:03:05.944716Z","steps":["trace[399983644] 'agreement among raft nodes before linearized reading' (duration: 906.465314ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:05.944734Z","caller":"traceutil/trace.go:171","msg":"trace[1426736209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"481.94068ms","start":"2026-04-23T17:03:05.462782Z","end":"2026-04-23T17:03:05.944723Z","steps":["trace[1426736209] 'agreement among raft nodes before linearized reading' (duration: 481.670425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.944759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.03802Z","time spent":"906.73137ms","remote":"127.0.0.1:32838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:05.944768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.462762Z","time spent":"481.996491ms","remote":"127.0.0.1:60984","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-04-23T17:03:05.944832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.981909ms","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-04-23T17:03:05.944923Z","caller":"traceutil/trace.go:171","msg":"trace[2022585339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"374.134662ms","start":"2026-04-23T17:03:05.570767Z","end":"2026-04-23T17:03:05.944902Z","steps":["trace[2022585339] 'agreement among raft nodes before linearized reading' (duration: 373.855757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.944951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.495792ms","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-04-23T17:03:05.944978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.570743Z","time spent":"374.224984ms","remote":"127.0.0.1:60984","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-04-23T17:03:05.944987Z","caller":"traceutil/trace.go:171","msg":"trace[1119080869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1852; }","duration":"371.570012ms","start":"2026-04-23T17:03:05.573406Z","end":"2026-04-23T17:03:05.944976Z","steps":["trace[1119080869] 'agreement among raft nodes before linearized reading' (duration: 371.133924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.945015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.573385Z","time spent":"371.623444ms","remote":"127.0.0.1:60984","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-04-23T17:03:05.945251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.275328ms","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-04-23T17:03:05.9453Z","caller":"traceutil/trace.go:171","msg":"trace[1510098405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"369.35852ms","start":"2026-04-23T17:03:05.575935Z","end":"2026-04-23T17:03:05.945293Z","steps":["trace[1510098405] 'agreement among raft nodes before linearized reading' (duration: 368.480284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.945318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.575915Z","time spent":"369.39901ms","remote":"127.0.0.1:60984","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-04-23T17:03:05.944268Z","caller":"traceutil/trace.go:171","msg":"trace[650438790] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"439.50825ms","start":"2026-04-23T17:03:05.504744Z","end":"2026-04-23T17:03:05.944253Z","steps":["trace[650438790] 'process raft request' (duration: 439.447189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:05.949539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:05.504725Z","time spent":"444.75938ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:06.193432Z","caller":"traceutil/trace.go:171","msg":"trace[2105373473] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"155.111391ms","start":"2026-04-23T17:03:06.038294Z","end":"2026-04-23T17:03:06.193405Z","steps":["trace[2105373473] 'process raft request' (duration: 155.04971ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:06.19374Z","caller":"traceutil/trace.go:171","msg":"trace[1107181075] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"157.007217ms","start":"2026-04-23T17:03:06.036609Z","end":"2026-04-23T17:03:06.193616Z","steps":["trace[1107181075] 'process raft request' (duration: 113.142889ms)","trace[1107181075] 'compare' (duration: 43.407339ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:06.210335Z","caller":"traceutil/trace.go:171","msg":"trace[1355742903] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"115.827041ms","start":"2026-04-23T17:03:06.094489Z","end":"2026-04-23T17:03:06.210316Z","steps":["trace[1355742903] 'process raft request' (duration: 115.685568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:06.558971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.086574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-23T17:03:06.559066Z","caller":"traceutil/trace.go:171","msg":"trace[844980750] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1867; }","duration":"262.207376ms","start":"2026-04-23T17:03:06.296831Z","end":"2026-04-23T17:03:06.559038Z","steps":["trace[844980750] 'range keys from in-memory index tree' (duration: 262.001652ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:06.810574Z","caller":"traceutil/trace.go:171","msg":"trace[1518189891] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"243.448027ms","start":"2026-04-23T17:03:06.567095Z","end":"2026-04-23T17:03:06.810543Z","steps":["trace[1518189891] 'process raft request' (duration: 243.261334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:07.454513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.990898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:07.454602Z","caller":"traceutil/trace.go:171","msg":"trace[843839928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"463.1393ms","start":"2026-04-23T17:03:06.991443Z","end":"2026-04-23T17:03:07.454582Z","steps":["trace[843839928] 'range keys from in-memory index tree' (duration: 462.877146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:07.454642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:06.99142Z","time spent":"463.213672ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:03:07.454726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.095044ms","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-04-23T17:03:07.45493Z","caller":"traceutil/trace.go:171","msg":"trace[714116755] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1868; }","duration":"271.305399ms","start":"2026-04-23T17:03:07.18361Z","end":"2026-04-23T17:03:07.454915Z","steps":["trace[714116755] 'range keys from in-memory index tree' (duration: 271.082104ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:08.115818Z","caller":"traceutil/trace.go:171","msg":"trace[1566485724] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"123.889225ms","start":"2026-04-23T17:03:07.991903Z","end":"2026-04-23T17:03:08.115792Z","steps":["trace[1566485724] 'read index received' (duration: 123.769302ms)","trace[1566485724] 'applied index is now lower than readState.Index' (duration: 118.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:08.115922Z","caller":"traceutil/trace.go:171","msg":"trace[382389612] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"176.646853ms","start":"2026-04-23T17:03:07.939241Z","end":"2026-04-23T17:03:08.115888Z","steps":["trace[382389612] 'process raft request' (duration: 176.375907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:08.115995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.08268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:08.116367Z","caller":"traceutil/trace.go:171","msg":"trace[1226260444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"124.513267ms","start":"2026-04-23T17:03:07.991843Z","end":"2026-04-23T17:03:08.116356Z","steps":["trace[1226260444] 'agreement among raft nodes before linearized reading' (duration: 124.079669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:08.609848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.178629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:03:08.610242Z","caller":"traceutil/trace.go:171","msg":"trace[102987242] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"489.720119ms","start":"2026-04-23T17:03:08.120443Z","end":"2026-04-23T17:03:08.610163Z","steps":["trace[102987242] 'process raft request' (duration: 254.08631ms)","trace[102987242] 'compare' (duration: 234.518097ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:08.610326Z","caller":"traceutil/trace.go:171","msg":"trace[363722539] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"487.619229ms","start":"2026-04-23T17:03:08.12268Z","end":"2026-04-23T17:03:08.6103Z","steps":["trace[363722539] 'process raft request' (duration: 487.311834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:08.61043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:08.122665Z","time spent":"487.719271ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:08.610513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:08.120397Z","time spent":"489.956083ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:08.610725Z","caller":"traceutil/trace.go:171","msg":"trace[2056528081] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"487.548277ms","start":"2026-04-23T17:03:08.123162Z","end":"2026-04-23T17:03:08.61071Z","steps":["trace[2056528081] 'process raft request' (duration: 487.362693ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:08.610722Z","caller":"traceutil/trace.go:171","msg":"trace[1530342790] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"487.938924ms","start":"2026-04-23T17:03:08.122754Z","end":"2026-04-23T17:03:08.610693Z","steps":["trace[1530342790] 'process raft request' (duration: 487.457276ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:08.610778Z","caller":"traceutil/trace.go:171","msg":"trace[1574520991] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"487.328383ms","start":"2026-04-23T17:03:08.123429Z","end":"2026-04-23T17:03:08.610757Z","steps":["trace[1574520991] 'process raft request' (duration: 487.18789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:08.610785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:08.123148Z","time spent":"487.600357ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:08.61083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:08.122724Z","time spent":"488.045586ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:08.610855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:08.123419Z","time spent":"487.394954ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:22.637075Z","caller":"traceutil/trace.go:171","msg":"trace[1796368233] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"271.92188ms","start":"2026-04-23T17:03:22.365127Z","end":"2026-04-23T17:03:22.637049Z","steps":["trace[1796368233] 'read index received' (duration: 271.731787ms)","trace[1796368233] 'applied index is now lower than readState.Index' (duration: 188.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:22.637186Z","caller":"traceutil/trace.go:171","msg":"trace[1298542348] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"469.943781ms","start":"2026-04-23T17:03:22.167208Z","end":"2026-04-23T17:03:22.637152Z","steps":["trace[1298542348] 'process raft request' (duration: 469.640446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:22.637281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.144735ms","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-04-23T17:03:22.637336Z","caller":"traceutil/trace.go:171","msg":"trace[1855470777] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1962; }","duration":"272.254326ms","start":"2026-04-23T17:03:22.365071Z","end":"2026-04-23T17:03:22.637326Z","steps":["trace[1855470777] 'agreement among raft nodes before linearized reading' (duration: 272.089333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:22.637357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.167179Z","time spent":"470.088804ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:23.154297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354204857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:03:23.655084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354204857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:03:24.155345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226229354204857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:03:24.254757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.607778841s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T17:03:24.428629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:23.39949Z","time spent":"1.029127036s","remote":"127.0.0.1:52758","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T17:03:24.42906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.931331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:03:24.429202Z","caller":"traceutil/trace.go:171","msg":"trace[1124197498] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"1.775108626s","start":"2026-04-23T17:03:22.654074Z","end":"2026-04-23T17:03:24.429182Z","steps":["trace[1124197498] 'read index received' (duration: 1.600842429s)","trace[1124197498] 'applied index is now lower than readState.Index' (duration: 174.264637ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:24.429255Z","caller":"traceutil/trace.go:171","msg":"trace[676336071] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"1.782354184s","start":"2026-04-23T17:03:22.646864Z","end":"2026-04-23T17:03:24.429218Z","steps":["trace[676336071] 'process raft request' (duration: 1.608091687s)","trace[676336071] 'compare' (duration: 173.321229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:24.42938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775227528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T17:03:24.429467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.646819Z","time spent":"1.782534688s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:24.429489Z","caller":"traceutil/trace.go:171","msg":"trace[560431201] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1963; }","duration":"1.775430832s","start":"2026-04-23T17:03:22.654035Z","end":"2026-04-23T17:03:24.429465Z","steps":["trace[560431201] 'agreement among raft nodes before linearized reading' (duration: 1.775196497s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.429581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.654025Z","time spent":"1.775541945s","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:24.461916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654254068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:24.462017Z","caller":"traceutil/trace.go:171","msg":"trace[2128785650] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"114.444505ms","start":"2026-04-23T17:03:24.347554Z","end":"2026-04-23T17:03:24.461999Z","steps":["trace[2128785650] 'process raft request' (duration: 114.042207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.462082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248657536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:03:24.462141Z","caller":"traceutil/trace.go:171","msg":"trace[714527711] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1964; }","duration":"1.248743287s","start":"2026-04-23T17:03:23.213376Z","end":"2026-04-23T17:03:24.462119Z","steps":["trace[714527711] 'agreement among raft nodes before linearized reading' (duration: 1.248680656s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.461926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468513834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:03:24.462208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:23.213353Z","time spent":"1.248838829s","remote":"127.0.0.1:32850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:24.462293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.776937911s","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-04-23T17:03:24.462349Z","caller":"traceutil/trace.go:171","msg":"trace[1769609541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1964; }","duration":"1.468952083s","start":"2026-04-23T17:03:22.993327Z","end":"2026-04-23T17:03:24.462279Z","steps":["trace[1769609541] 'agreement among raft nodes before linearized reading' (duration: 1.468455333s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.462031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.451208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T17:03:24.462442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.993305Z","time spent":"1.469093705s","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:03:24.462444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777723327s","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-04-23T17:03:24.462089Z","caller":"traceutil/trace.go:171","msg":"trace[1663644424] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1964; }","duration":"1.654463473s","start":"2026-04-23T17:03:22.807577Z","end":"2026-04-23T17:03:24.462041Z","steps":["trace[1663644424] 'agreement among raft nodes before linearized reading' (duration: 1.654238438s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.462459Z","caller":"traceutil/trace.go:171","msg":"trace[644725467] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1964; }","duration":"957.855887ms","start":"2026-04-23T17:03:23.504567Z","end":"2026-04-23T17:03:24.462423Z","steps":["trace[644725467] 'agreement among raft nodes before linearized reading' (duration: 957.410407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.462525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.807551Z","time spent":"1.654962492s","remote":"127.0.0.1:60856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-23T17:03:24.46257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:23.504525Z","time spent":"958.01782ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:24.462675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777194617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-23T17:03:24.462122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.776911291s","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-04-23T17:03:24.462715Z","caller":"traceutil/trace.go:171","msg":"trace[483570257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1964; }","duration":"1.777265768s","start":"2026-04-23T17:03:22.685438Z","end":"2026-04-23T17:03:24.462704Z","steps":["trace[483570257] 'agreement among raft nodes before linearized reading' (duration: 1.777119785s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.462728Z","caller":"traceutil/trace.go:171","msg":"trace[2072421271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.777545673s","start":"2026-04-23T17:03:22.685173Z","end":"2026-04-23T17:03:24.462718Z","steps":["trace[2072421271] 'agreement among raft nodes before linearized reading' (duration: 1.776860349s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.462765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.685166Z","time spent":"1.777587834s","remote":"127.0.0.1:60984","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-04-23T17:03:24.462502Z","caller":"traceutil/trace.go:171","msg":"trace[559522935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.777799678s","start":"2026-04-23T17:03:22.684691Z","end":"2026-04-23T17:03:24.462491Z","steps":["trace[559522935] 'agreement among raft nodes before linearized reading' (duration: 1.777696036s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.462348Z","caller":"traceutil/trace.go:171","msg":"trace[53897396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.777077605s","start":"2026-04-23T17:03:22.685252Z","end":"2026-04-23T17:03:24.46233Z","steps":["trace[53897396] 'agreement among raft nodes before linearized reading' (duration: 1.776922511s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.462759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.685431Z","time spent":"1.77732129s","remote":"127.0.0.1:60984","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-04-23T17:03:24.462895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.684667Z","time spent":"1.778214796s","remote":"127.0.0.1:60984","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-04-23T17:03:24.462837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698763799s","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:509"} {"level":"info","ts":"2026-04-23T17:03:24.463006Z","caller":"traceutil/trace.go:171","msg":"trace[709966789] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1964; }","duration":"1.698962842s","start":"2026-04-23T17:03:22.764028Z","end":"2026-04-23T17:03:24.462991Z","steps":["trace[709966789] 'agreement among raft nodes before linearized reading' (duration: 1.698762398s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.46298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.685237Z","time spent":"1.777670145s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:03:24.461944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.505487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:03:24.463052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.764016Z","time spent":"1.699024893s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-23T17:03:24.463087Z","caller":"traceutil/trace.go:171","msg":"trace[1623831051] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1964; }","duration":"542.72956ms","start":"2026-04-23T17:03:23.920341Z","end":"2026-04-23T17:03:24.46307Z","steps":["trace[1623831051] 'agreement among raft nodes before linearized reading' (duration: 541.535937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.463136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:23.920311Z","time spent":"542.809162ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:24.463247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778836467s","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-04-23T17:03:24.46334Z","caller":"traceutil/trace.go:171","msg":"trace[69503926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1964; }","duration":"1.780345186s","start":"2026-04-23T17:03:22.68298Z","end":"2026-04-23T17:03:24.463325Z","steps":["trace[69503926] 'agreement among raft nodes before linearized reading' (duration: 1.778757536s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.463409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:22.682952Z","time spent":"1.780443997s","remote":"127.0.0.1:60984","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-04-23T17:03:24.653128Z","caller":"traceutil/trace.go:171","msg":"trace[182355133] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"185.690905ms","start":"2026-04-23T17:03:24.467402Z","end":"2026-04-23T17:03:24.653093Z","steps":["trace[182355133] 'process raft request' (duration: 142.350448ms)","trace[182355133] 'compare' (duration: 43.072922ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:24.686592Z","caller":"traceutil/trace.go:171","msg":"trace[754901111] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"217.970381ms","start":"2026-04-23T17:03:24.468601Z","end":"2026-04-23T17:03:24.686571Z","steps":["trace[754901111] 'process raft request' (duration: 217.756047ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.686971Z","caller":"traceutil/trace.go:171","msg":"trace[1701254927] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"218.108314ms","start":"2026-04-23T17:03:24.468807Z","end":"2026-04-23T17:03:24.686915Z","steps":["trace[1701254927] 'process raft request' (duration: 217.712316ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.687225Z","caller":"traceutil/trace.go:171","msg":"trace[1241734927] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"217.645214ms","start":"2026-04-23T17:03:24.469565Z","end":"2026-04-23T17:03:24.687211Z","steps":["trace[1241734927] 'process raft request' (duration: 217.258477ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.687347Z","caller":"traceutil/trace.go:171","msg":"trace[691892540] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2051; }","duration":"218.507711ms","start":"2026-04-23T17:03:24.468806Z","end":"2026-04-23T17:03:24.687314Z","steps":["trace[691892540] 'read index received' (duration: 140.93332ms)","trace[691892540] 'applied index is now lower than readState.Index' (duration: 77.573171ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:24.687462Z","caller":"traceutil/trace.go:171","msg":"trace[1534973207] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"216.235187ms","start":"2026-04-23T17:03:24.471213Z","end":"2026-04-23T17:03:24.687448Z","steps":["trace[1534973207] 'process raft request' (duration: 216.047744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:24.687498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.689575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:24.687342Z","caller":"traceutil/trace.go:171","msg":"trace[451325018] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"216.306249ms","start":"2026-04-23T17:03:24.471017Z","end":"2026-04-23T17:03:24.687323Z","steps":["trace[451325018] 'process raft request' (duration: 215.901922ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:24.687534Z","caller":"traceutil/trace.go:171","msg":"trace[1769896048] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1970; }","duration":"218.767016ms","start":"2026-04-23T17:03:24.468757Z","end":"2026-04-23T17:03:24.687524Z","steps":["trace[1769896048] 'agreement among raft nodes before linearized reading' (duration: 218.698384ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:25.161535Z","caller":"traceutil/trace.go:171","msg":"trace[1701056662] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2057; }","duration":"474.099651ms","start":"2026-04-23T17:03:24.687402Z","end":"2026-04-23T17:03:25.161502Z","steps":["trace[1701056662] 'read index received' (duration: 382.303718ms)","trace[1701056662] 'applied index is now lower than readState.Index' (duration: 91.794513ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:25.161601Z","caller":"traceutil/trace.go:171","msg":"trace[1346607678] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"517.586081ms","start":"2026-04-23T17:03:24.643978Z","end":"2026-04-23T17:03:25.161564Z","steps":["trace[1346607678] 'process raft request' (duration: 425.737838ms)","trace[1346607678] 'compare' (duration: 91.64134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:25.161772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.509681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:03:25.161786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.483872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:03:25.161837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.869386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T17:03:25.161871Z","caller":"traceutil/trace.go:171","msg":"trace[949497388] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1971; }","duration":"526.700175ms","start":"2026-04-23T17:03:24.635149Z","end":"2026-04-23T17:03:25.161849Z","steps":["trace[949497388] 'agreement among raft nodes before linearized reading' (duration: 526.44482ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:25.161885Z","caller":"traceutil/trace.go:171","msg":"trace[1028362960] range","detail":"{range_begin:/registry/masterleases/199.19.213.204; range_end:; response_count:1; response_revision:1971; }","duration":"177.990708ms","start":"2026-04-23T17:03:24.983881Z","end":"2026-04-23T17:03:25.161872Z","steps":["trace[1028362960] 'agreement among raft nodes before linearized reading' (duration: 177.796975ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:25.161899Z","caller":"traceutil/trace.go:171","msg":"trace[184144529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1971; }","duration":"689.656125ms","start":"2026-04-23T17:03:24.472213Z","end":"2026-04-23T17:03:25.161869Z","steps":["trace[184144529] 'agreement among raft nodes before linearized reading' (duration: 689.451521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:25.161921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:24.635127Z","time spent":"526.783846ms","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:03:25.161961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:24.472201Z","time spent":"689.747026ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:03:25.1618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:24.643938Z","time spent":"517.740934ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:25.162141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.427048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:25.1623Z","caller":"traceutil/trace.go:171","msg":"trace[1492793126] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1971; }","duration":"143.704082ms","start":"2026-04-23T17:03:25.018563Z","end":"2026-04-23T17:03:25.162267Z","steps":["trace[1492793126] 'agreement among raft nodes before linearized reading' (duration: 143.213433ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:25.325425Z","caller":"traceutil/trace.go:171","msg":"trace[2097264975] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"137.25089ms","start":"2026-04-23T17:03:25.188122Z","end":"2026-04-23T17:03:25.325373Z","steps":["trace[2097264975] 'process raft request' (duration: 80.715651ms)","trace[2097264975] 'compare' (duration: 56.326266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:34.982578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.546341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:03:34.982685Z","caller":"traceutil/trace.go:171","msg":"trace[347804038] linearizableReadLoop","detail":"{readStateIndex:2107; appliedIndex:2106; }","duration":"234.243922ms","start":"2026-04-23T17:03:34.748425Z","end":"2026-04-23T17:03:34.982668Z","steps":["trace[347804038] 'read index received' (duration: 35.575049ms)","trace[347804038] 'applied index is now lower than readState.Index' (duration: 198.667343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:34.982871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.265161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-04-23T17:03:34.98291Z","caller":"traceutil/trace.go:171","msg":"trace[2027393616] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2017; }","duration":"245.415405ms","start":"2026-04-23T17:03:34.737484Z","end":"2026-04-23T17:03:34.9829Z","steps":["trace[2027393616] 'agreement among raft nodes before linearized reading' (duration: 245.326713ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:34.983368Z","caller":"traceutil/trace.go:171","msg":"trace[471515126] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"242.955607ms","start":"2026-04-23T17:03:34.739837Z","end":"2026-04-23T17:03:34.982792Z","steps":["trace[471515126] 'process raft request' (duration: 44.107411ms)","trace[471515126] 'compare' (duration: 198.176404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:35.319519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.154975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:03:35.319723Z","caller":"traceutil/trace.go:171","msg":"trace[703866209] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"567.201638ms","start":"2026-04-23T17:03:34.752503Z","end":"2026-04-23T17:03:35.319705Z","steps":["trace[703866209] 'process raft request' (duration: 567.113916ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.31975Z","caller":"traceutil/trace.go:171","msg":"trace[1371732062] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"567.276279ms","start":"2026-04-23T17:03:34.752425Z","end":"2026-04-23T17:03:35.319701Z","steps":["trace[1371732062] 'process raft request' (duration: 360.833829ms)","trace[1371732062] 'compare' (duration: 205.970821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:35.31982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.752497Z","time spent":"567.291189ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:35.319908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.75241Z","time spent":"567.415531ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:35.320012Z","caller":"traceutil/trace.go:171","msg":"trace[1220048750] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"567.444282ms","start":"2026-04-23T17:03:34.752543Z","end":"2026-04-23T17:03:35.319987Z","steps":["trace[1220048750] 'process raft request' (duration: 567.127796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.320156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.752538Z","time spent":"567.546804ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:35.320339Z","caller":"traceutil/trace.go:171","msg":"trace[1421974308] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"537.613953ms","start":"2026-04-23T17:03:34.7827Z","end":"2026-04-23T17:03:35.320314Z","steps":["trace[1421974308] 'process raft request' (duration: 537.413968ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.320407Z","caller":"traceutil/trace.go:171","msg":"trace[1477080271] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"567.458924ms","start":"2026-04-23T17:03:34.752938Z","end":"2026-04-23T17:03:35.320397Z","steps":["trace[1477080271] 'process raft request' (duration: 566.971294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.320427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.782685Z","time spent":"537.701324ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6937,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:03:35.320315Z","caller":"traceutil/trace.go:171","msg":"trace[240613425] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2107; }","duration":"337.536643ms","start":"2026-04-23T17:03:34.982756Z","end":"2026-04-23T17:03:35.320292Z","steps":["trace[240613425] 'read index received' (duration: 130.519942ms)","trace[240613425] 'applied index is now lower than readState.Index' (duration: 207.015541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:35.320522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.752931Z","time spent":"567.520955ms","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:03:35.320791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.226327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T17:03:35.320891Z","caller":"traceutil/trace.go:171","msg":"trace[1793944809] range","detail":"{range_begin:/registry/masterleases/199.19.213.204; range_end:; response_count:1; response_revision:2023; }","duration":"337.3732ms","start":"2026-04-23T17:03:34.983502Z","end":"2026-04-23T17:03:35.320875Z","steps":["trace[1793944809] 'agreement among raft nodes before linearized reading' (duration: 337.159126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.320951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.983472Z","time spent":"337.470562ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.204\" "} {"level":"warn","ts":"2026-04-23T17:03:35.32109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.226389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"warn","ts":"2026-04-23T17:03:35.32114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.021112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:35.321197Z","caller":"traceutil/trace.go:171","msg":"trace[249164308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2023; }","duration":"327.073143ms","start":"2026-04-23T17:03:34.994105Z","end":"2026-04-23T17:03:35.321178Z","steps":["trace[249164308] 'agreement among raft nodes before linearized reading' (duration: 326.985611ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.321202Z","caller":"traceutil/trace.go:171","msg":"trace[1157550083] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2023; }","duration":"269.322831ms","start":"2026-04-23T17:03:35.051807Z","end":"2026-04-23T17:03:35.32113Z","steps":["trace[1157550083] 'agreement among raft nodes before linearized reading' (duration: 269.219059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.321244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.994065Z","time spent":"327.170266ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:03:35.321146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.571746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-04-23T17:03:35.321367Z","caller":"traceutil/trace.go:171","msg":"trace[1507355717] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:2023; }","duration":"555.81458ms","start":"2026-04-23T17:03:34.765538Z","end":"2026-04-23T17:03:35.321352Z","steps":["trace[1507355717] 'agreement among raft nodes before linearized reading' (duration: 554.832602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.322403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:03:34.765529Z","time spent":"556.8418ms","remote":"127.0.0.1:32838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1966,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" "} {"level":"warn","ts":"2026-04-23T17:03:35.539244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.099479ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-23T17:03:35.539422Z","caller":"traceutil/trace.go:171","msg":"trace[250037589] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2113; }","duration":"217.057294ms","start":"2026-04-23T17:03:35.322348Z","end":"2026-04-23T17:03:35.539406Z","steps":["trace[250037589] 'read index received' (duration: 103.784652ms)","trace[250037589] 'applied index is now lower than readState.Index' (duration: 113.271132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:03:35.539552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.192836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:03:35.539591Z","caller":"traceutil/trace.go:171","msg":"trace[1013274467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2023; }","duration":"217.265967ms","start":"2026-04-23T17:03:35.322312Z","end":"2026-04-23T17:03:35.539578Z","steps":["trace[1013274467] 'agreement among raft nodes before linearized reading' (duration: 217.187536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.539772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.990081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-23T17:03:35.53983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-04-23T17:03:35.539892Z","caller":"traceutil/trace.go:171","msg":"trace[85122388] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2023; }","duration":"184.152194ms","start":"2026-04-23T17:03:35.355719Z","end":"2026-04-23T17:03:35.539871Z","steps":["trace[85122388] 'agreement among raft nodes before linearized reading' (duration: 183.844869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.539776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.783329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-04-23T17:03:35.540008Z","caller":"traceutil/trace.go:171","msg":"trace[1490330636] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:2023; }","duration":"150.068985ms","start":"2026-04-23T17:03:35.389923Z","end":"2026-04-23T17:03:35.539992Z","steps":["trace[1490330636] 'agreement among raft nodes before linearized reading' (duration: 149.661477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.539772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.1351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6951"} {"level":"info","ts":"2026-04-23T17:03:35.540161Z","caller":"traceutil/trace.go:171","msg":"trace[950173670] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2023; }","duration":"211.589189ms","start":"2026-04-23T17:03:35.328533Z","end":"2026-04-23T17:03:35.540122Z","steps":["trace[950173670] 'agreement among raft nodes before linearized reading' (duration: 211.014929ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.539925Z","caller":"traceutil/trace.go:171","msg":"trace[1733896707] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2023; }","duration":"211.240552ms","start":"2026-04-23T17:03:35.328664Z","end":"2026-04-23T17:03:35.539905Z","steps":["trace[1733896707] 'agreement among raft nodes before linearized reading' (duration: 211.023538ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.659339Z","caller":"traceutil/trace.go:171","msg":"trace[1424050928] linearizableReadLoop","detail":"{readStateIndex:2116; appliedIndex:2114; }","duration":"114.438606ms","start":"2026-04-23T17:03:35.544875Z","end":"2026-04-23T17:03:35.659313Z","steps":["trace[1424050928] 'read index received' (duration: 63.842719ms)","trace[1424050928] 'applied index is now lower than readState.Index' (duration: 50.594617ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:03:35.659805Z","caller":"traceutil/trace.go:171","msg":"trace[2124904054] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"115.477445ms","start":"2026-04-23T17:03:35.544297Z","end":"2026-04-23T17:03:35.659774Z","steps":["trace[2124904054] 'process raft request' (duration: 107.613064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:03:35.659882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.877873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-b5f4fb1e6cd909b01dda7ab2a8d320e6eeaeeadee3ad366131e28ac55b490ccf\" ","response":"range_response_count:1 size:651"} {"level":"warn","ts":"2026-04-23T17:03:35.659929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.015126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-04-23T17:03:35.659955Z","caller":"traceutil/trace.go:171","msg":"trace[247453076] range","detail":"{range_begin:/registry/volumeattachments/csi-b5f4fb1e6cd909b01dda7ab2a8d320e6eeaeeadee3ad366131e28ac55b490ccf; range_end:; response_count:1; response_revision:2025; }","duration":"115.001785ms","start":"2026-04-23T17:03:35.544932Z","end":"2026-04-23T17:03:35.659934Z","steps":["trace[247453076] 'agreement among raft nodes before linearized reading' (duration: 114.73165ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:03:35.660011Z","caller":"traceutil/trace.go:171","msg":"trace[1866830081] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:2025; }","duration":"115.150598ms","start":"2026-04-23T17:03:35.544839Z","end":"2026-04-23T17:03:35.65999Z","steps":["trace[1866830081] 'agreement among raft nodes before linearized reading' (duration: 114.847932ms)"],"step_count":1}