{"level":"warn","ts":"2026-04-20T10:13:52.174038Z","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-20T10:13:52.174143Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.23: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.23:2380","--initial-cluster=instance=https://199.19.213.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.23: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-20T10:13:52.174271Z","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-20T10:13:52.174287Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-04-20T10:13:52.174307Z","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-20T10:13:52.175145Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"]} {"level":"info","ts":"2026-04-20T10:13:52.175226Z","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.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.23: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-20T10:13:52.183242Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.749606ms"} {"level":"info","ts":"2026-04-20T10:13:52.189696Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1812db8446d3ac67","cluster-id":"57ff06cb872baa33"} {"level":"info","ts":"2026-04-20T10:13:52.189771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:13:52.189801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 0"} {"level":"info","ts":"2026-04-20T10:13:52.189825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1812db8446d3ac67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:13:52.189837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 1"} {"level":"info","ts":"2026-04-20T10:13:52.189875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"warn","ts":"2026-04-20T10:13:52.198005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:13:52.199601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:13:52.200901Z","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-20T10:13:52.205655Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1812db8446d3ac67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:13:52.205914Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1812db8446d3ac67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:13:52.206036Z","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-20T10:13:52.206189Z","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-20T10:13:52.206205Z","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-20T10:13:52.209561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"info","ts":"2026-04-20T10:13:52.209674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","added-peer-id":"1812db8446d3ac67","added-peer-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-04-20T10:13:52.211798Z","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-20T10:13:52.211961Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-04-20T10:13:52.212Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-04-20T10:13:52.212236Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1812db8446d3ac67","initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:13:52.212303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:13:52.490624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:13:52.490785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:13:52.490816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgPreVoteResp from 1812db8446d3ac67 at term 1"} {"level":"info","ts":"2026-04-20T10:13:52.490838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:13:52.491074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgVoteResp from 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-04-20T10:13:52.491099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became leader at term 2"} {"level":"info","ts":"2026-04-20T10:13:52.491114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1812db8446d3ac67 elected leader 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-04-20T10:13:52.493158Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:13:52.494768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1812db8446d3ac67","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.23:2379]}","request-path":"/0/members/1812db8446d3ac67/attributes","cluster-id":"57ff06cb872baa33","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:13:52.494972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:13:52.495036Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:13:52.495479Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:13:52.495776Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:13:52.495833Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:13:52.499186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.23:2379"} {"level":"info","ts":"2026-04-20T10:13:52.4989Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:13:52.499247Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:13:52.499339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T10:13:58.169382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.284466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T10:13:58.169556Z","caller":"traceutil/trace.go:171","msg":"trace[1102018340] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:304; }","duration":"104.549ms","start":"2026-04-20T10:13:58.064984Z","end":"2026-04-20T10:13:58.169533Z","steps":["trace[1102018340] 'range keys from in-memory index tree' (duration: 104.184665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.169685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.856046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-20T10:13:58.170045Z","caller":"traceutil/trace.go:171","msg":"trace[764639811] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:304; }","duration":"104.955358ms","start":"2026-04-20T10:13:58.064772Z","end":"2026-04-20T10:13:58.169728Z","steps":["trace[764639811] 'range keys from in-memory index tree' (duration: 104.634672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:58.173724Z","caller":"traceutil/trace.go:171","msg":"trace[2027637244] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"107.030184ms","start":"2026-04-20T10:13:58.066683Z","end":"2026-04-20T10:13:58.173713Z","steps":["trace[2027637244] 'process raft request' (duration: 106.973933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:58.574456Z","caller":"traceutil/trace.go:171","msg":"trace[1727646607] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"506.449249ms","start":"2026-04-20T10:13:58.067988Z","end":"2026-04-20T10:13:58.574438Z","steps":["trace[1727646607] 'process raft request' (duration: 506.431299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.574812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.067978Z","time spent":"506.53705ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:13:58.574986Z","caller":"traceutil/trace.go:171","msg":"trace[982403346] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"507.150912ms","start":"2026-04-20T10:13:58.067816Z","end":"2026-04-20T10:13:58.574967Z","steps":["trace[982403346] 'process raft request' (duration: 506.588721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:58.575064Z","caller":"traceutil/trace.go:171","msg":"trace[178207463] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"507.996427ms","start":"2026-04-20T10:13:58.067063Z","end":"2026-04-20T10:13:58.575059Z","steps":["trace[178207463] 'process raft request' (duration: 507.287394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.575074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.067809Z","time spent":"507.231823ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:13:58.575099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.067053Z","time spent":"508.030208ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:13:58.797903Z","caller":"traceutil/trace.go:171","msg":"trace[366840044] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"623.133193ms","start":"2026-04-20T10:13:58.174732Z","end":"2026-04-20T10:13:58.797865Z","steps":["trace[366840044] 'read index received' (duration: 400.21777ms)","trace[366840044] 'applied index is now lower than readState.Index' (duration: 222.914363ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:58.797988Z","caller":"traceutil/trace.go:171","msg":"trace[724876517] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"623.981319ms","start":"2026-04-20T10:13:58.173969Z","end":"2026-04-20T10:13:58.797951Z","steps":["trace[724876517] 'process raft request' (duration: 623.098383ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:58.79802Z","caller":"traceutil/trace.go:171","msg":"trace[1587177796] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"607.063003ms","start":"2026-04-20T10:13:58.19093Z","end":"2026-04-20T10:13:58.797993Z","steps":["trace[1587177796] 'process raft request' (duration: 606.853249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.798061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.044392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:58.798114Z","caller":"traceutil/trace.go:171","msg":"trace[1973209860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:306; }","duration":"607.126294ms","start":"2026-04-20T10:13:58.190979Z","end":"2026-04-20T10:13:58.798105Z","steps":["trace[1973209860] 'agreement among raft nodes before linearized reading' (duration: 607.047692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.798139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.190964Z","time spent":"607.170824ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:13:58.798135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.033228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-04-20T10:13:58.798145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.173916Z","time spent":"624.12574ms","remote":"127.0.0.1:55800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:13:58.798175Z","caller":"traceutil/trace.go:171","msg":"trace[543173573] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:306; }","duration":"222.136289ms","start":"2026-04-20T10:13:58.576027Z","end":"2026-04-20T10:13:58.798164Z","steps":["trace[543173573] 'agreement among raft nodes before linearized reading' (duration: 222.012367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.798274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.50701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4130"} {"level":"warn","ts":"2026-04-20T10:13:58.798304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.214282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-20T10:13:58.798366Z","caller":"traceutil/trace.go:171","msg":"trace[1274662980] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:306; }","duration":"623.665043ms","start":"2026-04-20T10:13:58.17468Z","end":"2026-04-20T10:13:58.798345Z","steps":["trace[1274662980] 'agreement among raft nodes before linearized reading' (duration: 623.454409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.79838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.190886Z","time spent":"607.194035ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:13:58.798418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:58.174659Z","time spent":"623.747734ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4152,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-20T10:13:58.798422Z","caller":"traceutil/trace.go:171","msg":"trace[1919430841] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:306; }","duration":"222.445546ms","start":"2026-04-20T10:13:58.575949Z","end":"2026-04-20T10:13:58.798394Z","steps":["trace[1919430841] 'agreement among raft nodes before linearized reading' (duration: 222.122851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:58.798684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.601149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-20T10:13:58.798738Z","caller":"traceutil/trace.go:171","msg":"trace[2003777347] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:306; }","duration":"222.66699ms","start":"2026-04-20T10:13:58.576055Z","end":"2026-04-20T10:13:58.798722Z","steps":["trace[2003777347] 'agreement among raft nodes before linearized reading' (duration: 222.569237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:59.087285Z","caller":"traceutil/trace.go:171","msg":"trace[341398771] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"283.326363ms","start":"2026-04-20T10:13:58.803928Z","end":"2026-04-20T10:13:59.087255Z","steps":["trace[341398771] 'read index received' (duration: 197.358007ms)","trace[341398771] 'applied index is now lower than readState.Index' (duration: 85.967316ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:59.087428Z","caller":"traceutil/trace.go:171","msg":"trace[602256730] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"284.153646ms","start":"2026-04-20T10:13:58.803222Z","end":"2026-04-20T10:13:59.087376Z","steps":["trace[602256730] 'process raft request' (duration: 198.051207ms)","trace[602256730] 'compare' (duration: 85.827145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:13:59.087504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.546387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T10:13:59.087561Z","caller":"traceutil/trace.go:171","msg":"trace[177779737] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:307; }","duration":"283.631268ms","start":"2026-04-20T10:13:58.803917Z","end":"2026-04-20T10:13:59.087548Z","steps":["trace[177779737] 'agreement among raft nodes before linearized reading' (duration: 283.459995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:59.095275Z","caller":"traceutil/trace.go:171","msg":"trace[681199060] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"286.569289ms","start":"2026-04-20T10:13:58.808693Z","end":"2026-04-20T10:13:59.095262Z","steps":["trace[681199060] 'process raft request' (duration: 286.406216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.756327Z","caller":"traceutil/trace.go:171","msg":"trace[2034837421] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"255.139071ms","start":"2026-04-20T10:14:21.501146Z","end":"2026-04-20T10:14:21.756285Z","steps":["trace[2034837421] 'process raft request' (duration: 254.837495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762072Z","caller":"traceutil/trace.go:171","msg":"trace[1862688661] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"250.106432ms","start":"2026-04-20T10:14:21.511944Z","end":"2026-04-20T10:14:21.76205Z","steps":["trace[1862688661] 'process raft request' (duration: 249.887048ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762106Z","caller":"traceutil/trace.go:171","msg":"trace[1204775300] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"242.678861ms","start":"2026-04-20T10:14:21.519396Z","end":"2026-04-20T10:14:21.762075Z","steps":["trace[1204775300] 'process raft request' (duration: 242.563819ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762192Z","caller":"traceutil/trace.go:171","msg":"trace[1470099154] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"235.644957ms","start":"2026-04-20T10:14:21.526508Z","end":"2026-04-20T10:14:21.762153Z","steps":["trace[1470099154] 'process raft request' (duration: 235.490264ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762315Z","caller":"traceutil/trace.go:171","msg":"trace[175034737] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"227.016536ms","start":"2026-04-20T10:14:21.535262Z","end":"2026-04-20T10:14:21.762279Z","steps":["trace[175034737] 'process raft request' (duration: 226.809191ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762399Z","caller":"traceutil/trace.go:171","msg":"trace[1727232807] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"216.886616ms","start":"2026-04-20T10:14:21.545505Z","end":"2026-04-20T10:14:21.762391Z","steps":["trace[1727232807] 'process raft request' (duration: 216.753974ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762359Z","caller":"traceutil/trace.go:171","msg":"trace[2027548539] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"223.443912ms","start":"2026-04-20T10:14:21.538905Z","end":"2026-04-20T10:14:21.762349Z","steps":["trace[2027548539] 'process raft request' (duration: 223.279488ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.76223Z","caller":"traceutil/trace.go:171","msg":"trace[62673008] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"227.024866ms","start":"2026-04-20T10:14:21.535183Z","end":"2026-04-20T10:14:21.762208Z","steps":["trace[62673008] 'process raft request' (duration: 226.853282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.762536Z","caller":"traceutil/trace.go:171","msg":"trace[1848781663] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"204.198743ms","start":"2026-04-20T10:14:21.558295Z","end":"2026-04-20T10:14:21.762494Z","steps":["trace[1848781663] 'process raft request' (duration: 204.03466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.763Z","caller":"traceutil/trace.go:171","msg":"trace[1798993615] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"198.173086ms","start":"2026-04-20T10:14:21.564812Z","end":"2026-04-20T10:14:21.762985Z","steps":["trace[1798993615] 'process raft request' (duration: 197.615946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:21.76329Z","caller":"traceutil/trace.go:171","msg":"trace[337196462] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"182.490949ms","start":"2026-04-20T10:14:21.58079Z","end":"2026-04-20T10:14:21.763281Z","steps":["trace[337196462] 'process raft request' (duration: 182.274665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.290747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.02087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:14:22.290965Z","caller":"traceutil/trace.go:171","msg":"trace[102391217] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"479.309856ms","start":"2026-04-20T10:14:21.811619Z","end":"2026-04-20T10:14:22.290928Z","steps":["trace[102391217] 'process raft request' (duration: 253.810818ms)","trace[102391217] 'compare' (duration: 224.886688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:22.291092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.811612Z","time spent":"479.416478ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:22.291448Z","caller":"traceutil/trace.go:171","msg":"trace[1579262340] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"474.337638ms","start":"2026-04-20T10:14:21.817094Z","end":"2026-04-20T10:14:22.291432Z","steps":["trace[1579262340] 'process raft request' (duration: 473.89092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.29155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.817085Z","time spent":"474.41627ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:22.291836Z","caller":"traceutil/trace.go:171","msg":"trace[1342056576] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"414.055705ms","start":"2026-04-20T10:14:21.877764Z","end":"2026-04-20T10:14:22.29182Z","steps":["trace[1342056576] 'process raft request' (duration: 413.820771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:22.291926Z","caller":"traceutil/trace.go:171","msg":"trace[1048177506] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"475.125083ms","start":"2026-04-20T10:14:21.816784Z","end":"2026-04-20T10:14:22.291909Z","steps":["trace[1048177506] 'read index received' (duration: 248.732908ms)","trace[1048177506] 'applied index is now lower than readState.Index' (duration: 226.390725ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:22.291941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.877743Z","time spent":"414.149127ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:22.292059Z","caller":"traceutil/trace.go:171","msg":"trace[806230137] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"449.378538ms","start":"2026-04-20T10:14:21.842668Z","end":"2026-04-20T10:14:22.292046Z","steps":["trace[806230137] 'process raft request' (duration: 448.480342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.292119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.842651Z","time spent":"449.43807ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:14:22.292141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.347526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-04-20T10:14:22.292186Z","caller":"traceutil/trace.go:171","msg":"trace[1127550222] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:537; }","duration":"475.420668ms","start":"2026-04-20T10:14:21.81675Z","end":"2026-04-20T10:14:22.292171Z","steps":["trace[1127550222] 'agreement among raft nodes before linearized reading' (duration: 475.249575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.292227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.816734Z","time spent":"475.481229ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1017,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-20T10:14:22.292232Z","caller":"traceutil/trace.go:171","msg":"trace[242210926] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"445.291456ms","start":"2026-04-20T10:14:21.846927Z","end":"2026-04-20T10:14:22.292218Z","steps":["trace[242210926] 'process raft request' (duration: 444.281108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.292305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.846905Z","time spent":"445.361748ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:22.292451Z","caller":"traceutil/trace.go:171","msg":"trace[539842195] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"440.543922ms","start":"2026-04-20T10:14:21.851893Z","end":"2026-04-20T10:14:22.292437Z","steps":["trace[539842195] 'process raft request' (duration: 439.485173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.292462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.036161ms","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-20T10:14:22.292506Z","caller":"traceutil/trace.go:171","msg":"trace[1122921607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"403.124963ms","start":"2026-04-20T10:14:21.889368Z","end":"2026-04-20T10:14:22.292493Z","steps":["trace[1122921607] 'agreement among raft nodes before linearized reading' (duration: 403.041771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:22.292527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.85188Z","time spent":"440.603752ms","remote":"127.0.0.1:55614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:14:22.292545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:21.889351Z","time spent":"403.183173ms","remote":"127.0.0.1:55870","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-20T10:14:22.292782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.962683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-20T10:14:22.292771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.211351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T10:14:22.292843Z","caller":"traceutil/trace.go:171","msg":"trace[812807668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"251.326903ms","start":"2026-04-20T10:14:22.041497Z","end":"2026-04-20T10:14:22.292824Z","steps":["trace[812807668] 'agreement among raft nodes before linearized reading' (duration: 251.212021ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:22.292848Z","caller":"traceutil/trace.go:171","msg":"trace[777811409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"244.028685ms","start":"2026-04-20T10:14:22.048787Z","end":"2026-04-20T10:14:22.292815Z","steps":["trace[777811409] 'agreement among raft nodes before linearized reading' (duration: 243.905683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:28.15407Z","caller":"traceutil/trace.go:171","msg":"trace[1431569564] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"131.52954ms","start":"2026-04-20T10:14:28.022508Z","end":"2026-04-20T10:14:28.154038Z","steps":["trace[1431569564] 'process raft request' (duration: 130.929989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:28.301459Z","caller":"traceutil/trace.go:171","msg":"trace[1233795707] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"128.63397ms","start":"2026-04-20T10:14:28.172801Z","end":"2026-04-20T10:14:28.301435Z","steps":["trace[1233795707] 'process raft request' (duration: 128.493867ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:28.498729Z","caller":"traceutil/trace.go:171","msg":"trace[152157005] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"132.817313ms","start":"2026-04-20T10:14:28.365876Z","end":"2026-04-20T10:14:28.498693Z","steps":["trace[152157005] 'read index received' (duration: 125.886881ms)","trace[152157005] 'applied index is now lower than readState.Index' (duration: 6.929402ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:28.498756Z","caller":"traceutil/trace.go:171","msg":"trace[1551592211] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"291.691455ms","start":"2026-04-20T10:14:28.207028Z","end":"2026-04-20T10:14:28.498719Z","steps":["trace[1551592211] 'process raft request' (duration: 284.834634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:28.498994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.352014ms","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-20T10:14:28.499029Z","caller":"traceutil/trace.go:171","msg":"trace[797726406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"120.438974ms","start":"2026-04-20T10:14:28.37858Z","end":"2026-04-20T10:14:28.499019Z","steps":["trace[797726406] 'agreement among raft nodes before linearized reading' (duration: 120.269102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:28.499255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.379763ms","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-20T10:14:28.499307Z","caller":"traceutil/trace.go:171","msg":"trace[1972600760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"133.500435ms","start":"2026-04-20T10:14:28.365792Z","end":"2026-04-20T10:14:28.499293Z","steps":["trace[1972600760] 'agreement among raft nodes before linearized reading' (duration: 133.041487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:28.500213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.031472ms","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-20T10:14:28.501786Z","caller":"traceutil/trace.go:171","msg":"trace[860699347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"120.470135ms","start":"2026-04-20T10:14:28.38126Z","end":"2026-04-20T10:14:28.50173Z","steps":["trace[860699347] 'agreement among raft nodes before linearized reading' (duration: 117.91137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:29.297867Z","caller":"traceutil/trace.go:171","msg":"trace[1570781536] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"368.496262ms","start":"2026-04-20T10:14:28.929328Z","end":"2026-04-20T10:14:29.297824Z","steps":["trace[1570781536] 'process raft request' (duration: 367.460584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:29.298179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:28.929295Z","time spent":"368.722846ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:45.493843Z","caller":"traceutil/trace.go:171","msg":"trace[1825678347] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"114.118584ms","start":"2026-04-20T10:14:45.379705Z","end":"2026-04-20T10:14:45.493824Z","steps":["trace[1825678347] 'read index received' (duration: 113.942291ms)","trace[1825678347] 'applied index is now lower than readState.Index' (duration: 175.403µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:45.494291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.562611ms","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-20T10:14:45.494656Z","caller":"traceutil/trace.go:171","msg":"trace[2119629337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"114.936738ms","start":"2026-04-20T10:14:45.379669Z","end":"2026-04-20T10:14:45.494606Z","steps":["trace[2119629337] 'agreement among raft nodes before linearized reading' (duration: 114.351817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:45.494372Z","caller":"traceutil/trace.go:171","msg":"trace[205864157] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"240.050905ms","start":"2026-04-20T10:14:45.254304Z","end":"2026-04-20T10:14:45.494355Z","steps":["trace[205864157] 'process raft request' (duration: 239.406574ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:16.755632Z","caller":"traceutil/trace.go:171","msg":"trace[1950615451] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"265.279281ms","start":"2026-04-20T10:15:16.490337Z","end":"2026-04-20T10:15:16.755616Z","steps":["trace[1950615451] 'process raft request' (duration: 265.168619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:20.704827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.405946ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:15:21.226012Z","caller":"traceutil/trace.go:171","msg":"trace[1652508151] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"410.64264ms","start":"2026-04-20T10:15:20.815339Z","end":"2026-04-20T10:15:21.225981Z","steps":["trace[1652508151] 'read index received' (duration: 410.457226ms)","trace[1652508151] 'applied index is now lower than readState.Index' (duration: 184.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:21.226042Z","caller":"traceutil/trace.go:171","msg":"trace[1384011463] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"460.025173ms","start":"2026-04-20T10:15:20.765976Z","end":"2026-04-20T10:15:21.226001Z","steps":["trace[1384011463] 'process raft request' (duration: 459.795808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:21.226238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:20.765947Z","time spent":"460.188666ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:15:21.226325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.957315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T10:15:21.226374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.460861ms","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-20T10:15:21.226407Z","caller":"traceutil/trace.go:171","msg":"trace[544616700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"411.093018ms","start":"2026-04-20T10:15:20.815296Z","end":"2026-04-20T10:15:21.226389Z","steps":["trace[544616700] 'agreement among raft nodes before linearized reading' (duration: 410.784883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:21.226421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.893836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:15:21.226458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:20.815276Z","time spent":"411.172809ms","remote":"127.0.0.1:55870","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-20T10:15:21.226455Z","caller":"traceutil/trace.go:171","msg":"trace[351743088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"152.585543ms","start":"2026-04-20T10:15:21.073852Z","end":"2026-04-20T10:15:21.226438Z","steps":["trace[351743088] 'agreement among raft nodes before linearized reading' (duration: 152.348199ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:21.226482Z","caller":"traceutil/trace.go:171","msg":"trace[171122261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"401.968377ms","start":"2026-04-20T10:15:20.824501Z","end":"2026-04-20T10:15:21.226469Z","steps":["trace[171122261] 'agreement among raft nodes before linearized reading' (duration: 401.870625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:21.226339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.908605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T10:15:21.226522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:20.824492Z","time spent":"402.021477ms","remote":"127.0.0.1:55870","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-20T10:15:21.226574Z","caller":"traceutil/trace.go:171","msg":"trace[1283353440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"403.170339ms","start":"2026-04-20T10:15:20.823379Z","end":"2026-04-20T10:15:21.226549Z","steps":["trace[1283353440] 'agreement among raft nodes before linearized reading' (duration: 402.814033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:21.226657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:20.823369Z","time spent":"403.278802ms","remote":"127.0.0.1:55870","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-20T10:15:21.421928Z","caller":"traceutil/trace.go:171","msg":"trace[1402918596] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"190.264708ms","start":"2026-04-20T10:15:21.231643Z","end":"2026-04-20T10:15:21.421908Z","steps":["trace[1402918596] 'process raft request' (duration: 190.237048ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:21.422056Z","caller":"traceutil/trace.go:171","msg":"trace[1723586431] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"191.050624ms","start":"2026-04-20T10:15:21.230957Z","end":"2026-04-20T10:15:21.422007Z","steps":["trace[1723586431] 'process raft request' (duration: 190.739157ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:21.422182Z","caller":"traceutil/trace.go:171","msg":"trace[1131806264] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"191.029993ms","start":"2026-04-20T10:15:21.231105Z","end":"2026-04-20T10:15:21.422135Z","steps":["trace[1131806264] 'process raft request' (duration: 190.742848ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:23.462785Z","caller":"traceutil/trace.go:171","msg":"trace[184443377] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"199.739054ms","start":"2026-04-20T10:15:23.263024Z","end":"2026-04-20T10:15:23.462763Z","steps":["trace[184443377] 'read index received' (duration: 199.559931ms)","trace[184443377] 'applied index is now lower than readState.Index' (duration: 178.133µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:23.462863Z","caller":"traceutil/trace.go:171","msg":"trace[1863177976] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"230.636033ms","start":"2026-04-20T10:15:23.23218Z","end":"2026-04-20T10:15:23.462816Z","steps":["trace[1863177976] 'process raft request' (duration: 230.44079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:23.463006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.974659ms","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-20T10:15:23.463053Z","caller":"traceutil/trace.go:171","msg":"trace[353275477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"200.056291ms","start":"2026-04-20T10:15:23.262976Z","end":"2026-04-20T10:15:23.463032Z","steps":["trace[353275477] 'agreement among raft nodes before linearized reading' (duration: 199.906209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:23.617515Z","caller":"traceutil/trace.go:171","msg":"trace[2014670525] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"150.523345ms","start":"2026-04-20T10:15:23.466968Z","end":"2026-04-20T10:15:23.617491Z","steps":["trace[2014670525] 'process raft request' (duration: 150.375212ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:23.617668Z","caller":"traceutil/trace.go:171","msg":"trace[1896392536] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"150.658348ms","start":"2026-04-20T10:15:23.466996Z","end":"2026-04-20T10:15:23.617654Z","steps":["trace[1896392536] 'process raft request' (duration: 150.458635ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:23.617714Z","caller":"traceutil/trace.go:171","msg":"trace[1230705805] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"149.955804ms","start":"2026-04-20T10:15:23.467745Z","end":"2026-04-20T10:15:23.617701Z","steps":["trace[1230705805] 'process raft request' (duration: 149.853232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:23.896661Z","caller":"traceutil/trace.go:171","msg":"trace[62136787] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"114.876818ms","start":"2026-04-20T10:15:23.78176Z","end":"2026-04-20T10:15:23.896637Z","steps":["trace[62136787] 'process raft request' (duration: 114.739096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:24.138226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.28158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:15:24.138295Z","caller":"traceutil/trace.go:171","msg":"trace[360092720] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1010; }","duration":"120.404402ms","start":"2026-04-20T10:15:24.017876Z","end":"2026-04-20T10:15:24.13828Z","steps":["trace[360092720] 'count revisions from in-memory index tree' (duration: 120.221988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:24.138318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.954834ms","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-20T10:15:24.138394Z","caller":"traceutil/trace.go:171","msg":"trace[245015586] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1010; }","duration":"112.089246ms","start":"2026-04-20T10:15:24.026291Z","end":"2026-04-20T10:15:24.13838Z","steps":["trace[245015586] 'count revisions from in-memory index tree' (duration: 111.846892ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:25.725184Z","caller":"traceutil/trace.go:171","msg":"trace[1634184578] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"101.09579ms","start":"2026-04-20T10:15:25.624069Z","end":"2026-04-20T10:15:25.725164Z","steps":["trace[1634184578] 'process raft request' (duration: 87.706089ms)","trace[1634184578] 'compare' (duration: 13.29795ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:25.72528Z","caller":"traceutil/trace.go:171","msg":"trace[645168688] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"100.56373ms","start":"2026-04-20T10:15:25.624711Z","end":"2026-04-20T10:15:25.725275Z","steps":["trace[645168688] 'process raft request' (duration: 100.419718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:27.755239Z","caller":"traceutil/trace.go:171","msg":"trace[1326374360] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"137.335088ms","start":"2026-04-20T10:15:27.617871Z","end":"2026-04-20T10:15:27.755207Z","steps":["trace[1326374360] 'process raft request' (duration: 137.168505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:30.695415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.432077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:15:42.31524Z","caller":"traceutil/trace.go:171","msg":"trace[675022777] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"452.980761ms","start":"2026-04-20T10:15:41.862239Z","end":"2026-04-20T10:15:42.31522Z","steps":["trace[675022777] 'read index received' (duration: 452.771647ms)","trace[675022777] 'applied index is now lower than readState.Index' (duration: 208.294µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:42.315309Z","caller":"traceutil/trace.go:171","msg":"trace[1537230981] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"484.058082ms","start":"2026-04-20T10:15:41.831221Z","end":"2026-04-20T10:15:42.315279Z","steps":["trace[1537230981] 'process raft request' (duration: 483.868499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.315437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:41.831207Z","time spent":"484.159904ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:15:42.315446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.361735ms","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-20T10:15:42.315475Z","caller":"traceutil/trace.go:171","msg":"trace[670664936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"440.430396ms","start":"2026-04-20T10:15:41.875036Z","end":"2026-04-20T10:15:42.315467Z","steps":["trace[670664936] 'agreement among raft nodes before linearized reading' (duration: 440.304543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.315507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:41.875023Z","time spent":"440.478686ms","remote":"127.0.0.1:55870","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-20T10:15:42.315521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.251686ms","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-20T10:15:42.3156Z","caller":"traceutil/trace.go:171","msg":"trace[348452534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"453.389059ms","start":"2026-04-20T10:15:41.862192Z","end":"2026-04-20T10:15:42.315581Z","steps":["trace[348452534] 'agreement among raft nodes before linearized reading' (duration: 453.118294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.315632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.290441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:15:42.315658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:41.86217Z","time spent":"453.467241ms","remote":"127.0.0.1:55870","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-20T10:15:42.31563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.700952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T10:15:42.315703Z","caller":"traceutil/trace.go:171","msg":"trace[1105117365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"434.394093ms","start":"2026-04-20T10:15:41.881287Z","end":"2026-04-20T10:15:42.315681Z","steps":["trace[1105117365] 'agreement among raft nodes before linearized reading' (duration: 434.189669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:42.315732Z","caller":"traceutil/trace.go:171","msg":"trace[1921255209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"278.859665ms","start":"2026-04-20T10:15:42.036847Z","end":"2026-04-20T10:15:42.315706Z","steps":["trace[1921255209] 'agreement among raft nodes before linearized reading' (duration: 278.56098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.315757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:41.881272Z","time spent":"434.473905ms","remote":"127.0.0.1:55870","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-20T10:15:42.863891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.896512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:15:42.864144Z","caller":"traceutil/trace.go:171","msg":"trace[1405237800] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"543.036575ms","start":"2026-04-20T10:15:42.321091Z","end":"2026-04-20T10:15:42.864127Z","steps":["trace[1405237800] 'process raft request' (duration: 542.976404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.864216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:42.321072Z","time spent":"543.111206ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:15:42.864248Z","caller":"traceutil/trace.go:171","msg":"trace[1135182403] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"544.37006ms","start":"2026-04-20T10:15:42.319852Z","end":"2026-04-20T10:15:42.864222Z","steps":["trace[1135182403] 'process raft request' (duration: 231.02627ms)","trace[1135182403] 'compare' (duration: 312.478963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:15:42.864372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:42.31983Z","time spent":"544.477492ms","remote":"127.0.0.1:55870","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-20T10:15:42.864393Z","caller":"traceutil/trace.go:171","msg":"trace[2006148957] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"543.363671ms","start":"2026-04-20T10:15:42.32102Z","end":"2026-04-20T10:15:42.864383Z","steps":["trace[2006148957] 'process raft request' (duration: 542.971254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.864442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:42.321001Z","time spent":"543.415422ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:15:42.864611Z","caller":"traceutil/trace.go:171","msg":"trace[42132492] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"541.913754ms","start":"2026-04-20T10:15:42.322683Z","end":"2026-04-20T10:15:42.864597Z","steps":["trace[42132492] 'process raft request' (duration: 541.413385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:42.864676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:42.322672Z","time spent":"541.973175ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:15:44.525363Z","caller":"traceutil/trace.go:171","msg":"trace[555068233] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"202.037338ms","start":"2026-04-20T10:15:44.323306Z","end":"2026-04-20T10:15:44.525344Z","steps":["trace[555068233] 'process raft request' (duration: 201.927756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:45.769212Z","caller":"traceutil/trace.go:171","msg":"trace[2085023733] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"124.568489ms","start":"2026-04-20T10:15:45.64462Z","end":"2026-04-20T10:15:45.769189Z","steps":["trace[2085023733] 'process raft request' (duration: 106.43073ms)","trace[2085023733] 'compare' (duration: 17.915635ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:15:45.860548Z","caller":"traceutil/trace.go:171","msg":"trace[1496290306] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"207.987089ms","start":"2026-04-20T10:15:45.652519Z","end":"2026-04-20T10:15:45.860506Z","steps":["trace[1496290306] 'process raft request' (duration: 207.751165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:57.981059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039807625s","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-20T10:15:57.981123Z","caller":"traceutil/trace.go:171","msg":"trace[1655947899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"1.039923447s","start":"2026-04-20T10:15:56.941188Z","end":"2026-04-20T10:15:57.981111Z","steps":["trace[1655947899] 'range keys from in-memory index tree' (duration: 1.039669762s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:57.981144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:56.941158Z","time spent":"1.039981458s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:15:57.981184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.076351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:15:57.981243Z","caller":"traceutil/trace.go:171","msg":"trace[789814653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"555.204453ms","start":"2026-04-20T10:15:57.426024Z","end":"2026-04-20T10:15:57.981229Z","steps":["trace[789814653] 'range keys from in-memory index tree' (duration: 554.934509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:57.981267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.425983Z","time spent":"555.278775ms","remote":"127.0.0.1:55560","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-20T10:15:57.981334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040183872s","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-20T10:15:57.981352Z","caller":"traceutil/trace.go:171","msg":"trace[1794709665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1165; }","duration":"1.040233483s","start":"2026-04-20T10:15:56.941113Z","end":"2026-04-20T10:15:57.981346Z","steps":["trace[1794709665] 'range keys from in-memory index tree' (duration: 1.04010221s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:57.981365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:56.941095Z","time spent":"1.040266713s","remote":"127.0.0.1:55870","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-20T10:15:57.981367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037722886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T10:15:57.981367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046772025s","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-20T10:15:57.981443Z","caller":"traceutil/trace.go:171","msg":"trace[1979696560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"1.046881148s","start":"2026-04-20T10:15:56.934548Z","end":"2026-04-20T10:15:57.981429Z","steps":["trace[1979696560] 'range keys from in-memory index tree' (duration: 1.046568411s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:15:57.981444Z","caller":"traceutil/trace.go:171","msg":"trace[683665028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"1.037820578s","start":"2026-04-20T10:15:56.943602Z","end":"2026-04-20T10:15:57.981423Z","steps":["trace[683665028] 'range keys from in-memory index tree' (duration: 1.037593863s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:57.981486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:56.934534Z","time spent":"1.046943359s","remote":"127.0.0.1:55870","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-20T10:15:57.981486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:56.943597Z","time spent":"1.037880479s","remote":"127.0.0.1:55870","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-20T10:15:58.047403Z","caller":"traceutil/trace.go:171","msg":"trace[1642662680] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"149.778141ms","start":"2026-04-20T10:15:57.897597Z","end":"2026-04-20T10:15:58.047376Z","steps":["trace[1642662680] 'process raft request' (duration: 149.593677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:58.547683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071452236334828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:15:59.048477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071452236334828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:15:59.549478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071452236334828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:15:59.983121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999003172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T10:15:59.983208Z","caller":"traceutil/trace.go:171","msg":"trace[771852106] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999118515s","start":"2026-04-20T10:15:57.984067Z","end":"2026-04-20T10:15:59.983186Z","steps":["trace[771852106] 'agreement among raft nodes before linearized reading' (duration: 1.999020863s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:15:59.983244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.984052Z","time spent":"1.999184876s","remote":"127.0.0.1:55574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 10:15:59 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T10:16:00.049998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071452236334828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:16:00.550855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071452236334828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:16:00.72119Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.674004735s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T10:16:01.820172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098728537s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:01.820346Z","caller":"traceutil/trace.go:171","msg":"trace[1071671801] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"3.835597358s","start":"2026-04-20T10:15:57.98473Z","end":"2026-04-20T10:16:01.820327Z","steps":["trace[1071671801] 'process raft request' (duration: 2.736625456s)","trace[1071671801] 'compare' (duration: 1.097445673s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:01.820407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.984718Z","time spent":"3.835659119s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:16:01.82062Z","caller":"traceutil/trace.go:171","msg":"trace[1315043000] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"3.835320142s","start":"2026-04-20T10:15:57.985284Z","end":"2026-04-20T10:16:01.820604Z","steps":["trace[1315043000] 'process raft request' (duration: 3.835289951s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:01.820719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.985276Z","time spent":"3.835395353s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:16:01.82112Z","caller":"traceutil/trace.go:171","msg":"trace[855637177] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"3.836101166s","start":"2026-04-20T10:15:57.984992Z","end":"2026-04-20T10:16:01.821094Z","steps":["trace[855637177] 'process raft request' (duration: 3.835536606s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:01.821256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.984985Z","time spent":"3.836202587s","remote":"127.0.0.1:55870","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-20T10:16:01.820923Z","caller":"traceutil/trace.go:171","msg":"trace[1987858760] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"3.835999854s","start":"2026-04-20T10:15:57.984917Z","end":"2026-04-20T10:16:01.820917Z","steps":["trace[1987858760] 'process raft request' (duration: 3.835343603s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:01.821652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:57.984907Z","time spent":"3.836723368s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:01.99095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000009071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T10:16:01.991038Z","caller":"traceutil/trace.go:171","msg":"trace[1439010886] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000148494s","start":"2026-04-20T10:15:59.99087Z","end":"2026-04-20T10:16:01.991018Z","steps":["trace[1439010886] 'agreement among raft nodes before linearized reading' (duration: 2.000035882s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:01.991106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:59.990853Z","time spent":"2.000243375s","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:16:03.584028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.006323ms","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-20T10:16:03.584159Z","caller":"traceutil/trace.go:171","msg":"trace[1089598175] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1170; }","duration":"444.149706ms","start":"2026-04-20T10:16:03.139992Z","end":"2026-04-20T10:16:03.584142Z","steps":["trace[1089598175] 'range keys from in-memory index tree' (duration: 443.987862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.584856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.001323557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T10:16:03.584896Z","caller":"traceutil/trace.go:171","msg":"trace[1821435900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.001390839s","start":"2026-04-20T10:15:58.583494Z","end":"2026-04-20T10:16:03.584885Z","steps":["trace[1821435900] 'agreement among raft nodes before linearized reading' (duration: 5.001346478s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.584954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:58.583481Z","time spent":"5.001441739s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:16:03.604727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.883346199s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:16:03.605176Z","caller":"traceutil/trace.go:171","msg":"trace[1211540077] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1209; }","duration":"5.557855554s","start":"2026-04-20T10:15:58.047305Z","end":"2026-04-20T10:16:03.60516Z","steps":["trace[1211540077] 'read index received' (duration: 2.674060346s)","trace[1211540077] 'applied index is now lower than readState.Index' (duration: 2.883794298s)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:03.605267Z","caller":"traceutil/trace.go:171","msg":"trace[1215484001] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"4.570134543s","start":"2026-04-20T10:15:59.035123Z","end":"2026-04-20T10:16:03.605258Z","steps":["trace[1215484001] 'process raft request' (duration: 4.569756905s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:03.605333Z","caller":"traceutil/trace.go:171","msg":"trace[1908047171] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"3.553930621s","start":"2026-04-20T10:16:00.051383Z","end":"2026-04-20T10:16:03.605314Z","steps":["trace[1908047171] 'process raft request' (duration: 3.553733627s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.605371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:59.035103Z","time spent":"4.570197605s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:03.605445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:00.051357Z","time spent":"3.554016642s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:16:03.605588Z","caller":"traceutil/trace.go:171","msg":"trace[1393642737] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"3.619522037s","start":"2026-04-20T10:15:59.986055Z","end":"2026-04-20T10:16:03.605577Z","steps":["trace[1393642737] 'process raft request' (duration: 3.619003537s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.605647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:59.986034Z","time spent":"3.619587307s","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:16:03.605675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.584968001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:16:03.605719Z","caller":"traceutil/trace.go:171","msg":"trace[1069650113] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1173; }","duration":"5.585048823s","start":"2026-04-20T10:15:58.02066Z","end":"2026-04-20T10:16:03.605709Z","steps":["trace[1069650113] 'agreement among raft nodes before linearized reading' (duration: 5.584974682s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.605759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:58.020642Z","time spent":"5.585108424s","remote":"127.0.0.1:37382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:03.992161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999807927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T10:16:03.992245Z","caller":"traceutil/trace.go:171","msg":"trace[96687392] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999935849s","start":"2026-04-20T10:16:01.992292Z","end":"2026-04-20T10:16:03.992228Z","steps":["trace[96687392] 'agreement among raft nodes before linearized reading' (duration: 1.999833527s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:03.992295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:01.992275Z","time spent":"2.00001048s","remote":"127.0.0.1:55574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:16:04.099097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.648008ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:16:04.099293Z","caller":"traceutil/trace.go:171","msg":"trace[974181774] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"494.031038ms","start":"2026-04-20T10:16:03.605228Z","end":"2026-04-20T10:16:04.099259Z","steps":["trace[974181774] 'read index received' (duration: 205.145006ms)","trace[974181774] 'applied index is now lower than readState.Index' (duration: 288.883472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:04.099493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475226068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:16:04.099529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.000855729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:16:04.09954Z","caller":"traceutil/trace.go:171","msg":"trace[1710304654] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1173; }","duration":"1.475312529s","start":"2026-04-20T10:16:02.624213Z","end":"2026-04-20T10:16:04.099525Z","steps":["trace[1710304654] 'agreement among raft nodes before linearized reading' (duration: 1.475255998s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.099565Z","caller":"traceutil/trace.go:171","msg":"trace[1685439126] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1173; }","duration":"6.00092122s","start":"2026-04-20T10:15:58.098631Z","end":"2026-04-20T10:16:04.099553Z","steps":["trace[1685439126] 'agreement among raft nodes before linearized reading' (duration: 6.000853848s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.099592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:02.624193Z","time spent":"1.475390321s","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:04.099597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:58.098615Z","time spent":"6.000974621s","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:04.099557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.382263991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:04.099564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.050318963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:04.099623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.914560644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:04.099411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.109377727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:04.099686Z","caller":"traceutil/trace.go:171","msg":"trace[1601140925] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1173; }","duration":"5.050468846s","start":"2026-04-20T10:15:59.049191Z","end":"2026-04-20T10:16:04.099659Z","steps":["trace[1601140925] 'agreement among raft nodes before linearized reading' (duration: 5.050264882s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.099661Z","caller":"traceutil/trace.go:171","msg":"trace[447972462] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1173; }","duration":"5.382380303s","start":"2026-04-20T10:15:58.717256Z","end":"2026-04-20T10:16:04.099636Z","steps":["trace[447972462] 'agreement among raft nodes before linearized reading' (duration: 5.38221129s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.099745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:59.049133Z","time spent":"5.050600148s","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:04.099764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:58.717198Z","time spent":"5.382547556s","remote":"127.0.0.1:56044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:04.099765Z","caller":"traceutil/trace.go:171","msg":"trace[1774527986] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1173; }","duration":"3.109770734s","start":"2026-04-20T10:16:00.989979Z","end":"2026-04-20T10:16:04.09975Z","steps":["trace[1774527986] 'agreement among raft nodes before linearized reading' (duration: 3.109383597s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.099886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:00.989958Z","time spent":"3.109910506s","remote":"127.0.0.1:55718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:04.099936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.950301ms","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-20T10:16:04.099979Z","caller":"traceutil/trace.go:171","msg":"trace[1153416696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"275.075774ms","start":"2026-04-20T10:16:03.824893Z","end":"2026-04-20T10:16:04.099969Z","steps":["trace[1153416696] 'agreement among raft nodes before linearized reading' (duration: 274.951902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.099454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.26511914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:16:04.100016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.150435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T10:16:04.099895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.593834ms","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-20T10:16:04.100065Z","caller":"traceutil/trace.go:171","msg":"trace[1153434990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"275.233918ms","start":"2026-04-20T10:16:03.824817Z","end":"2026-04-20T10:16:04.100051Z","steps":["trace[1153434990] 'agreement among raft nodes before linearized reading' (duration: 275.105685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.100062Z","caller":"traceutil/trace.go:171","msg":"trace[1361346833] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1173; }","duration":"4.265756712s","start":"2026-04-20T10:15:59.834285Z","end":"2026-04-20T10:16:04.100041Z","steps":["trace[1361346833] 'agreement among raft nodes before linearized reading' (duration: 4.26509123s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.100171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.428911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T10:16:04.100195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.433812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a8093137504b6d\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-04-20T10:16:04.100212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:15:59.834272Z","time spent":"4.265927615s","remote":"127.0.0.1:55886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:04.100239Z","caller":"traceutil/trace.go:171","msg":"trace[1994897786] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a8093137504b6d; range_end:; response_count:1; response_revision:1173; }","duration":"104.502674ms","start":"2026-04-20T10:16:03.995725Z","end":"2026-04-20T10:16:04.100228Z","steps":["trace[1994897786] 'agreement among raft nodes before linearized reading' (duration: 104.359471ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.100206Z","caller":"traceutil/trace.go:171","msg":"trace[1797532709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"275.489632ms","start":"2026-04-20T10:16:03.824709Z","end":"2026-04-20T10:16:04.100199Z","steps":["trace[1797532709] 'agreement among raft nodes before linearized reading' (duration: 275.38794ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.100069Z","caller":"traceutil/trace.go:171","msg":"trace[1118161434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"275.805778ms","start":"2026-04-20T10:16:03.824253Z","end":"2026-04-20T10:16:04.100059Z","steps":["trace[1118161434] 'agreement among raft nodes before linearized reading' (duration: 275.489972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.099442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.764389456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:04.100735Z","caller":"traceutil/trace.go:171","msg":"trace[1134934667] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1173; }","duration":"3.765712501s","start":"2026-04-20T10:16:00.335001Z","end":"2026-04-20T10:16:04.100713Z","steps":["trace[1134934667] 'agreement among raft nodes before linearized reading' (duration: 3.764389246s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.100814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:00.334983Z","time spent":"3.765803393s","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:04.099686Z","caller":"traceutil/trace.go:171","msg":"trace[207362209] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1173; }","duration":"3.914672466s","start":"2026-04-20T10:16:00.184999Z","end":"2026-04-20T10:16:04.099671Z","steps":["trace[207362209] 'agreement among raft nodes before linearized reading' (duration: 3.914465452s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.101054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:00.184978Z","time spent":"3.916056582s","remote":"127.0.0.1:55852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:04.567988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.734607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:04.568232Z","caller":"traceutil/trace.go:171","msg":"trace[1843411264] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"462.342956ms","start":"2026-04-20T10:16:04.10587Z","end":"2026-04-20T10:16:04.568213Z","steps":["trace[1843411264] 'process raft request' (duration: 462.202313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.568257Z","caller":"traceutil/trace.go:171","msg":"trace[1978441273] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"462.407036ms","start":"2026-04-20T10:16:04.105826Z","end":"2026-04-20T10:16:04.568234Z","steps":["trace[1978441273] 'process raft request' (duration: 294.358624ms)","trace[1978441273] 'compare' (duration: 167.456852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:04.568301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:04.105849Z","time spent":"462.420787ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:04.568317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.436146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:04.56831Z","caller":"traceutil/trace.go:171","msg":"trace[1221608306] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"461.588611ms","start":"2026-04-20T10:16:04.106698Z","end":"2026-04-20T10:16:04.568286Z","steps":["trace[1221608306] 'process raft request' (duration: 461.454619ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.568349Z","caller":"traceutil/trace.go:171","msg":"trace[1056746085] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1178; }","duration":"286.504368ms","start":"2026-04-20T10:16:04.281835Z","end":"2026-04-20T10:16:04.568339Z","steps":["trace[1056746085] 'agreement among raft nodes before linearized reading' (duration: 286.428136ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:04.568333Z","caller":"traceutil/trace.go:171","msg":"trace[492111419] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"461.379658ms","start":"2026-04-20T10:16:04.106923Z","end":"2026-04-20T10:16:04.568302Z","steps":["trace[492111419] 'process raft request' (duration: 461.268416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:04.568369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:04.105803Z","time spent":"462.503579ms","remote":"127.0.0.1:55870","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-20T10:16:04.56824Z","caller":"traceutil/trace.go:171","msg":"trace[1461192036] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1218; }","duration":"286.343554ms","start":"2026-04-20T10:16:04.281885Z","end":"2026-04-20T10:16:04.568229Z","steps":["trace[1461192036] 'read index received' (duration: 118.21712ms)","trace[1461192036] 'applied index is now lower than readState.Index' (duration: 168.125234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:04.568476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:04.106684Z","time spent":"461.693064ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:04.56853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:04.106913Z","time spent":"461.522401ms","remote":"127.0.0.1:55870","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-20T10:16:06.697112Z","caller":"traceutil/trace.go:171","msg":"trace[368965534] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"119.355462ms","start":"2026-04-20T10:16:06.577728Z","end":"2026-04-20T10:16:06.697084Z","steps":["trace[368965534] 'process raft request' (duration: 119.293441ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:06.697353Z","caller":"traceutil/trace.go:171","msg":"trace[1263870073] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"120.068516ms","start":"2026-04-20T10:16:06.577259Z","end":"2026-04-20T10:16:06.697328Z","steps":["trace[1263870073] 'process raft request' (duration: 91.846108ms)","trace[1263870073] 'compare' (duration: 27.741699ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:19.858021Z","caller":"traceutil/trace.go:171","msg":"trace[256341867] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"163.135906ms","start":"2026-04-20T10:16:19.694858Z","end":"2026-04-20T10:16:19.857994Z","steps":["trace[256341867] 'read index received' (duration: 162.906891ms)","trace[256341867] 'applied index is now lower than readState.Index' (duration: 227.645µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:19.858259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.39863ms","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-20T10:16:19.858291Z","caller":"traceutil/trace.go:171","msg":"trace[222403767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"163.491372ms","start":"2026-04-20T10:16:19.694791Z","end":"2026-04-20T10:16:19.858282Z","steps":["trace[222403767] 'agreement among raft nodes before linearized reading' (duration: 163.324129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:19.858532Z","caller":"traceutil/trace.go:171","msg":"trace[1401666641] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"172.257319ms","start":"2026-04-20T10:16:19.686262Z","end":"2026-04-20T10:16:19.858519Z","steps":["trace[1401666641] 'process raft request' (duration: 171.566447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:20.108287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.851786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:20.108419Z","caller":"traceutil/trace.go:171","msg":"trace[1501034092] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"245.655491ms","start":"2026-04-20T10:16:19.862742Z","end":"2026-04-20T10:16:20.108397Z","steps":["trace[1501034092] 'process raft request' (duration: 57.583429ms)","trace[1501034092] 'compare' (duration: 187.753945ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:20.453641Z","caller":"traceutil/trace.go:171","msg":"trace[641040920] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"176.770184ms","start":"2026-04-20T10:16:20.276843Z","end":"2026-04-20T10:16:20.453613Z","steps":["trace[641040920] 'process raft request' (duration: 176.609681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:24.212255Z","caller":"traceutil/trace.go:171","msg":"trace[520012704] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"105.7759ms","start":"2026-04-20T10:16:24.106445Z","end":"2026-04-20T10:16:24.212221Z","steps":["trace[520012704] 'process raft request' (duration: 30.124165ms)","trace[520012704] 'compare' (duration: 75.347848ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:26.417958Z","caller":"traceutil/trace.go:171","msg":"trace[1414071139] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"143.9949ms","start":"2026-04-20T10:16:26.273929Z","end":"2026-04-20T10:16:26.417924Z","steps":["trace[1414071139] 'process raft request' (duration: 143.810376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:27.036894Z","caller":"traceutil/trace.go:171","msg":"trace[2143089287] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"183.254059ms","start":"2026-04-20T10:16:26.8536Z","end":"2026-04-20T10:16:27.036854Z","steps":["trace[2143089287] 'read index received' (duration: 183.162047ms)","trace[2143089287] 'applied index is now lower than readState.Index' (duration: 91.042µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:27.036981Z","caller":"traceutil/trace.go:171","msg":"trace[1160104152] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"245.808154ms","start":"2026-04-20T10:16:26.791138Z","end":"2026-04-20T10:16:27.036946Z","steps":["trace[1160104152] 'process raft request' (duration: 245.583469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:27.037138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.534414ms","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-20T10:16:27.037178Z","caller":"traceutil/trace.go:171","msg":"trace[501701022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"183.642076ms","start":"2026-04-20T10:16:26.853525Z","end":"2026-04-20T10:16:27.037167Z","steps":["trace[501701022] 'agreement among raft nodes before linearized reading' (duration: 183.491943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:27.037463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.595998ms","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-20T10:16:27.037518Z","caller":"traceutil/trace.go:171","msg":"trace[153584658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"147.690119ms","start":"2026-04-20T10:16:26.889815Z","end":"2026-04-20T10:16:27.037506Z","steps":["trace[153584658] 'agreement among raft nodes before linearized reading' (duration: 147.514996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:27.525617Z","caller":"traceutil/trace.go:171","msg":"trace[1470744910] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"484.755265ms","start":"2026-04-20T10:16:27.040832Z","end":"2026-04-20T10:16:27.525587Z","steps":["trace[1470744910] 'process raft request' (duration: 470.638745ms)","trace[1470744910] 'compare' (duration: 13.817814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:27.52577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:27.040808Z","time spent":"484.894588ms","remote":"127.0.0.1:55870","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-20T10:16:27.543628Z","caller":"traceutil/trace.go:171","msg":"trace[1574959825] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1332; }","duration":"118.662166ms","start":"2026-04-20T10:16:27.424945Z","end":"2026-04-20T10:16:27.543607Z","steps":["trace[1574959825] 'read index received' (duration: 86.563983ms)","trace[1574959825] 'applied index is now lower than readState.Index' (duration: 32.097573ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:27.543706Z","caller":"traceutil/trace.go:171","msg":"trace[601411993] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"502.366431ms","start":"2026-04-20T10:16:27.041307Z","end":"2026-04-20T10:16:27.543674Z","steps":["trace[601411993] 'process raft request' (duration: 502.097746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:27.54381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.837919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:27.543841Z","caller":"traceutil/trace.go:171","msg":"trace[1806552759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"118.91654ms","start":"2026-04-20T10:16:27.424916Z","end":"2026-04-20T10:16:27.543833Z","steps":["trace[1806552759] 'agreement among raft nodes before linearized reading' (duration: 118.768048ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:27.543971Z","caller":"traceutil/trace.go:171","msg":"trace[2134207216] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"367.989866ms","start":"2026-04-20T10:16:27.175924Z","end":"2026-04-20T10:16:27.543913Z","steps":["trace[2134207216] 'process raft request' (duration: 367.64821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:27.544016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:27.041296Z","time spent":"502.538325ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:27.544431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:27.175905Z","time spent":"368.406224ms","remote":"127.0.0.1:55870","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-20T10:16:28.754061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.561562ms","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-20T10:16:28.754118Z","caller":"traceutil/trace.go:171","msg":"trace[1181672268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"328.662844ms","start":"2026-04-20T10:16:28.425443Z","end":"2026-04-20T10:16:28.754106Z","steps":["trace[1181672268] 'range keys from in-memory index tree' (duration: 328.43046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:28.75414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:28.425423Z","time spent":"328.712715ms","remote":"127.0.0.1:55870","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-20T10:16:28.754126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.999074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:16:28.754128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.280247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:28.754194Z","caller":"traceutil/trace.go:171","msg":"trace[428688307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1294; }","duration":"205.130026ms","start":"2026-04-20T10:16:28.549046Z","end":"2026-04-20T10:16:28.754176Z","steps":["trace[428688307] 'range keys from in-memory index tree' (duration: 204.81647ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:28.754224Z","caller":"traceutil/trace.go:171","msg":"trace[1719724462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"328.42814ms","start":"2026-04-20T10:16:28.425776Z","end":"2026-04-20T10:16:28.754204Z","steps":["trace[1719724462] 'range keys from in-memory index tree' (duration: 328.194556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:28.754265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:28.425754Z","time spent":"328.502032ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T10:16:28.983407Z","caller":"traceutil/trace.go:171","msg":"trace[214608913] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"225.595727ms","start":"2026-04-20T10:16:28.757772Z","end":"2026-04-20T10:16:28.983368Z","steps":["trace[214608913] 'process raft request' (duration: 225.440624ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:29.333463Z","caller":"traceutil/trace.go:171","msg":"trace[1660604363] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"291.825701ms","start":"2026-04-20T10:16:29.041615Z","end":"2026-04-20T10:16:29.33344Z","steps":["trace[1660604363] 'read index received' (duration: 291.641258ms)","trace[1660604363] 'applied index is now lower than readState.Index' (duration: 183.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:29.333802Z","caller":"traceutil/trace.go:171","msg":"trace[1040857544] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"575.666161ms","start":"2026-04-20T10:16:28.758121Z","end":"2026-04-20T10:16:29.333787Z","steps":["trace[1040857544] 'process raft request' (duration: 575.191642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:29.33393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:28.758105Z","time spent":"575.735652ms","remote":"127.0.0.1:55870","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-20T10:16:29.334109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.481834ms","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-20T10:16:29.33458Z","caller":"traceutil/trace.go:171","msg":"trace[975925661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"293.009034ms","start":"2026-04-20T10:16:29.041552Z","end":"2026-04-20T10:16:29.334561Z","steps":["trace[975925661] 'agreement among raft nodes before linearized reading' (duration: 292.326421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:30.20913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.616119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:16:30.209207Z","caller":"traceutil/trace.go:171","msg":"trace[910806278] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1301; }","duration":"146.715061ms","start":"2026-04-20T10:16:30.062473Z","end":"2026-04-20T10:16:30.209189Z","steps":["trace[910806278] 'count revisions from in-memory index tree' (duration: 146.364535ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:30.959272Z","caller":"traceutil/trace.go:171","msg":"trace[1083036544] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"331.60561ms","start":"2026-04-20T10:16:30.62764Z","end":"2026-04-20T10:16:30.959246Z","steps":["trace[1083036544] 'read index received' (duration: 331.439887ms)","trace[1083036544] 'applied index is now lower than readState.Index' (duration: 164.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:30.95929Z","caller":"traceutil/trace.go:171","msg":"trace[1125673026] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"404.859789ms","start":"2026-04-20T10:16:30.554409Z","end":"2026-04-20T10:16:30.959268Z","steps":["trace[1125673026] 'process raft request' (duration: 404.695766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:30.959499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.816835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:16:30.959547Z","caller":"traceutil/trace.go:171","msg":"trace[1501365413] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1302; }","duration":"331.957618ms","start":"2026-04-20T10:16:30.627579Z","end":"2026-04-20T10:16:30.959537Z","steps":["trace[1501365413] 'agreement among raft nodes before linearized reading' (duration: 331.758793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:30.959615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:30.627556Z","time spent":"332.04658ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:30.959687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:30.554385Z","time spent":"405.096005ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6319,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:31.522415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.316721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:16:31.522518Z","caller":"traceutil/trace.go:171","msg":"trace[1793147300] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"563.087821ms","start":"2026-04-20T10:16:30.959413Z","end":"2026-04-20T10:16:31.522501Z","steps":["trace[1793147300] 'read index received' (duration: 16.685459ms)","trace[1793147300] 'applied index is now lower than readState.Index' (duration: 546.400592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:31.522913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.46954ms","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-20T10:16:31.522958Z","caller":"traceutil/trace.go:171","msg":"trace[1774481312] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"758.559032ms","start":"2026-04-20T10:16:30.764387Z","end":"2026-04-20T10:16:31.522946Z","steps":["trace[1774481312] 'agreement among raft nodes before linearized reading' (duration: 758.41335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:31.522989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:30.764365Z","time spent":"758.616374ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-20T10:16:31.522951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.028377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-20T10:16:31.522985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.640506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:16:31.52298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.764421ms","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-20T10:16:31.522963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.729039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T10:16:31.523193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.517482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:16:31.523165Z","caller":"traceutil/trace.go:171","msg":"trace[165963911] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1302; }","duration":"115.977514ms","start":"2026-04-20T10:16:31.407172Z","end":"2026-04-20T10:16:31.523149Z","steps":["trace[165963911] 'agreement among raft nodes before linearized reading' (duration: 115.704939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:31.523289Z","caller":"traceutil/trace.go:171","msg":"trace[1569676915] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1302; }","duration":"736.600333ms","start":"2026-04-20T10:16:30.786621Z","end":"2026-04-20T10:16:31.523222Z","steps":["trace[1569676915] 'agreement among raft nodes before linearized reading' (duration: 736.496001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:31.523308Z","caller":"traceutil/trace.go:171","msg":"trace[1717099888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"536.054944ms","start":"2026-04-20T10:16:30.987158Z","end":"2026-04-20T10:16:31.523213Z","steps":["trace[1717099888] 'agreement among raft nodes before linearized reading' (duration: 535.634097ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:31.523053Z","caller":"traceutil/trace.go:171","msg":"trace[1836941524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"136.164959ms","start":"2026-04-20T10:16:31.38687Z","end":"2026-04-20T10:16:31.523035Z","steps":["trace[1836941524] 'agreement among raft nodes before linearized reading' (duration: 135.921705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:31.523419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:30.786593Z","time spent":"736.761036ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:31.523433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:30.987141Z","time spent":"536.280379ms","remote":"127.0.0.1:55870","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-20T10:16:31.523086Z","caller":"traceutil/trace.go:171","msg":"trace[498439179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"183.790118ms","start":"2026-04-20T10:16:31.33927Z","end":"2026-04-20T10:16:31.52306Z","steps":["trace[498439179] 'agreement among raft nodes before linearized reading' (duration: 183.530244ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:32.68158Z","caller":"traceutil/trace.go:171","msg":"trace[779391142] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"114.438384ms","start":"2026-04-20T10:16:32.567117Z","end":"2026-04-20T10:16:32.681556Z","steps":["trace[779391142] 'process raft request' (duration: 114.175839ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:44.249263Z","caller":"traceutil/trace.go:171","msg":"trace[1120358562] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"138.753479ms","start":"2026-04-20T10:16:44.110483Z","end":"2026-04-20T10:16:44.249236Z","steps":["trace[1120358562] 'process raft request' (duration: 138.595336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:44.35328Z","caller":"traceutil/trace.go:171","msg":"trace[1326350740] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"103.663629ms","start":"2026-04-20T10:16:44.249586Z","end":"2026-04-20T10:16:44.35325Z","steps":["trace[1326350740] 'process raft request' (duration: 36.421015ms)","trace[1326350740] 'compare' (duration: 67.107312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:44.637516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.838712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:44.637614Z","caller":"traceutil/trace.go:171","msg":"trace[2143658681] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"210.847016ms","start":"2026-04-20T10:16:44.426749Z","end":"2026-04-20T10:16:44.637596Z","steps":["trace[2143658681] 'read index received' (duration: 72.578266ms)","trace[2143658681] 'applied index is now lower than readState.Index' (duration: 138.26715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:44.637905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.137061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:44.638009Z","caller":"traceutil/trace.go:171","msg":"trace[1959975779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1383; }","duration":"211.288085ms","start":"2026-04-20T10:16:44.426698Z","end":"2026-04-20T10:16:44.637986Z","steps":["trace[1959975779] 'agreement among raft nodes before linearized reading' (duration: 211.100831ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:44.637953Z","caller":"traceutil/trace.go:171","msg":"trace[993636692] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"254.938018ms","start":"2026-04-20T10:16:44.382941Z","end":"2026-04-20T10:16:44.637879Z","steps":["trace[993636692] 'process raft request' (duration: 116.465684ms)","trace[993636692] 'compare' (duration: 137.674998ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:36.64888Z","caller":"traceutil/trace.go:171","msg":"trace[526262559] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"127.669387ms","start":"2026-04-20T10:18:36.521179Z","end":"2026-04-20T10:18:36.648849Z","steps":["trace[526262559] 'process raft request' (duration: 127.470474ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:36.661827Z","caller":"traceutil/trace.go:171","msg":"trace[1209482263] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"140.290661ms","start":"2026-04-20T10:18:36.521515Z","end":"2026-04-20T10:18:36.661806Z","steps":["trace[1209482263] 'process raft request' (duration: 140.155849ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:36.662143Z","caller":"traceutil/trace.go:171","msg":"trace[416546379] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"140.455215ms","start":"2026-04-20T10:18:36.521631Z","end":"2026-04-20T10:18:36.662086Z","steps":["trace[416546379] 'process raft request' (duration: 140.147559ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:40.029291Z","caller":"traceutil/trace.go:171","msg":"trace[1429147758] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"110.225893ms","start":"2026-04-20T10:18:39.919041Z","end":"2026-04-20T10:18:40.029267Z","steps":["trace[1429147758] 'process raft request' (duration: 110.095961ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:40.720245Z","caller":"traceutil/trace.go:171","msg":"trace[930512213] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"430.440503ms","start":"2026-04-20T10:18:40.289783Z","end":"2026-04-20T10:18:40.720224Z","steps":["trace[930512213] 'process raft request' (duration: 430.30709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:40.720319Z","caller":"traceutil/trace.go:171","msg":"trace[825442649] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1986; }","duration":"317.777474ms","start":"2026-04-20T10:18:40.402512Z","end":"2026-04-20T10:18:40.720289Z","steps":["trace[825442649] 'read index received' (duration: 317.762103ms)","trace[825442649] 'applied index is now lower than readState.Index' (duration: 12.891µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:18:40.720377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:40.289768Z","time spent":"430.538465ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:18:40.720581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.059189ms","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-20T10:18:40.720642Z","caller":"traceutil/trace.go:171","msg":"trace[992309948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"318.14678ms","start":"2026-04-20T10:18:40.40248Z","end":"2026-04-20T10:18:40.720626Z","steps":["trace[992309948] 'agreement among raft nodes before linearized reading' (duration: 317.934986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:40.720677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:40.402465Z","time spent":"318.204351ms","remote":"127.0.0.1:55870","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-20T10:18:40.753929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.191938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:18:40.753981Z","caller":"traceutil/trace.go:171","msg":"trace[474905210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1912; }","duration":"327.28923ms","start":"2026-04-20T10:18:40.42668Z","end":"2026-04-20T10:18:40.75397Z","steps":["trace[474905210] 'agreement among raft nodes before linearized reading' (duration: 327.215399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:40.754001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:40.426659Z","time spent":"327.337321ms","remote":"127.0.0.1:55560","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-20T10:18:40.754106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.278956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:18:40.754161Z","caller":"traceutil/trace.go:171","msg":"trace[544652943] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1912; }","duration":"226.336947ms","start":"2026-04-20T10:18:40.527816Z","end":"2026-04-20T10:18:40.754153Z","steps":["trace[544652943] 'agreement among raft nodes before linearized reading' (duration: 226.254785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:40.754241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.261457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:18:40.75431Z","caller":"traceutil/trace.go:171","msg":"trace[584518798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"260.384509ms","start":"2026-04-20T10:18:40.493906Z","end":"2026-04-20T10:18:40.754291Z","steps":["trace[584518798] 'agreement among raft nodes before linearized reading' (duration: 260.194016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:47.130582Z","caller":"traceutil/trace.go:171","msg":"trace[334788324] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2016; }","duration":"173.07886ms","start":"2026-04-20T10:18:46.957473Z","end":"2026-04-20T10:18:47.130552Z","steps":["trace[334788324] 'read index received' (duration: 23.967455ms)","trace[334788324] 'applied index is now lower than readState.Index' (duration: 149.110155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:18:47.130787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.284733ms","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-20T10:18:47.130844Z","caller":"traceutil/trace.go:171","msg":"trace[414293848] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1945; }","duration":"173.397836ms","start":"2026-04-20T10:18:46.95743Z","end":"2026-04-20T10:18:47.130828Z","steps":["trace[414293848] 'agreement among raft nodes before linearized reading' (duration: 173.231232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:47.130793Z","caller":"traceutil/trace.go:171","msg":"trace[667464065] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"198.182876ms","start":"2026-04-20T10:18:46.932573Z","end":"2026-04-20T10:18:47.130756Z","steps":["trace[667464065] 'process raft request' (duration: 181.516297ms)","trace[667464065] 'compare' (duration: 16.318202ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:53.061758Z","caller":"traceutil/trace.go:171","msg":"trace[495708415] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"104.070411ms","start":"2026-04-20T10:18:52.957658Z","end":"2026-04-20T10:18:53.061729Z","steps":["trace[495708415] 'process raft request' (duration: 72.067557ms)","trace[495708415] 'compare' (duration: 31.811919ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:03.511928Z","caller":"traceutil/trace.go:171","msg":"trace[852870719] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2093; }","duration":"278.211179ms","start":"2026-04-20T10:19:03.233693Z","end":"2026-04-20T10:19:03.511904Z","steps":["trace[852870719] 'read index received' (duration: 278.084747ms)","trace[852870719] 'applied index is now lower than readState.Index' (duration: 124.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:03.511968Z","caller":"traceutil/trace.go:171","msg":"trace[415732028] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"286.888151ms","start":"2026-04-20T10:19:03.225061Z","end":"2026-04-20T10:19:03.51195Z","steps":["trace[415732028] 'process raft request' (duration: 286.704517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:03.512146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.442294ms","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-20T10:19:03.512189Z","caller":"traceutil/trace.go:171","msg":"trace[593809098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2014; }","duration":"278.530486ms","start":"2026-04-20T10:19:03.233648Z","end":"2026-04-20T10:19:03.512178Z","steps":["trace[593809098] 'agreement among raft nodes before linearized reading' (duration: 278.386183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:03.512236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.874032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:19:03.512273Z","caller":"traceutil/trace.go:171","msg":"trace[199298135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2014; }","duration":"230.909952ms","start":"2026-04-20T10:19:03.281354Z","end":"2026-04-20T10:19:03.512264Z","steps":["trace[199298135] 'agreement among raft nodes before linearized reading' (duration: 230.822441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:03.512259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.215445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T10:19:03.512159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.951023ms","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-20T10:19:03.512353Z","caller":"traceutil/trace.go:171","msg":"trace[1192011305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2014; }","duration":"274.328478ms","start":"2026-04-20T10:19:03.237989Z","end":"2026-04-20T10:19:03.512318Z","steps":["trace[1192011305] 'agreement among raft nodes before linearized reading' (duration: 274.084203ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:03.512372Z","caller":"traceutil/trace.go:171","msg":"trace[870290033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2014; }","duration":"231.172409ms","start":"2026-04-20T10:19:03.281175Z","end":"2026-04-20T10:19:03.512347Z","steps":["trace[870290033] 'agreement among raft nodes before linearized reading' (duration: 230.924353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:03.51244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.35057ms","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-20T10:19:03.512502Z","caller":"traceutil/trace.go:171","msg":"trace[122539415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2014; }","duration":"232.453451ms","start":"2026-04-20T10:19:03.280031Z","end":"2026-04-20T10:19:03.512485Z","steps":["trace[122539415] 'agreement among raft nodes before linearized reading' (duration: 232.194746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:03.958769Z","caller":"traceutil/trace.go:171","msg":"trace[1740045633] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"100.419752ms","start":"2026-04-20T10:19:03.858321Z","end":"2026-04-20T10:19:03.958741Z","steps":["trace[1740045633] 'process raft request' (duration: 100.246989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:03.9632Z","caller":"traceutil/trace.go:171","msg":"trace[336907199] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"103.445459ms","start":"2026-04-20T10:19:03.859738Z","end":"2026-04-20T10:19:03.963183Z","steps":["trace[336907199] 'process raft request' (duration: 103.357957ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:33.259209Z","caller":"traceutil/trace.go:171","msg":"trace[1657724315] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"152.956566ms","start":"2026-04-20T10:19:33.106197Z","end":"2026-04-20T10:19:33.259154Z","steps":["trace[1657724315] 'process raft request' (duration: 152.809803ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:38.290673Z","caller":"traceutil/trace.go:171","msg":"trace[9695153] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"247.4027ms","start":"2026-04-20T10:19:38.043233Z","end":"2026-04-20T10:19:38.290635Z","steps":["trace[9695153] 'process raft request' (duration: 207.770321ms)","trace[9695153] 'compare' (duration: 39.275823ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:38.291001Z","caller":"traceutil/trace.go:171","msg":"trace[125600514] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"241.641933ms","start":"2026-04-20T10:19:38.049352Z","end":"2026-04-20T10:19:38.290994Z","steps":["trace[125600514] 'process raft request' (duration: 241.50377ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:38.291172Z","caller":"traceutil/trace.go:171","msg":"trace[1401826542] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"246.082436ms","start":"2026-04-20T10:19:38.045082Z","end":"2026-04-20T10:19:38.291164Z","steps":["trace[1401826542] 'process raft request' (duration: 245.628378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:38.291263Z","caller":"traceutil/trace.go:171","msg":"trace[206253564] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"241.989149ms","start":"2026-04-20T10:19:38.049268Z","end":"2026-04-20T10:19:38.291257Z","steps":["trace[206253564] 'process raft request' (duration: 241.551582ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:38.570778Z","caller":"traceutil/trace.go:171","msg":"trace[2013848718] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"144.135032ms","start":"2026-04-20T10:19:38.426613Z","end":"2026-04-20T10:19:38.570748Z","steps":["trace[2013848718] 'read index received' (duration: 72.82727ms)","trace[2013848718] 'applied index is now lower than readState.Index' (duration: 71.306282ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:38.57111Z","caller":"traceutil/trace.go:171","msg":"trace[1764972400] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"208.457843ms","start":"2026-04-20T10:19:38.36258Z","end":"2026-04-20T10:19:38.571038Z","steps":["trace[1764972400] 'process raft request' (duration: 136.916517ms)","trace[1764972400] 'compare' (duration: 70.860024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:38.57126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.62438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:38.571333Z","caller":"traceutil/trace.go:171","msg":"trace[127422704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"144.745163ms","start":"2026-04-20T10:19:38.426568Z","end":"2026-04-20T10:19:38.571313Z","steps":["trace[127422704] 'agreement among raft nodes before linearized reading' (duration: 144.280105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:44.186334Z","caller":"traceutil/trace.go:171","msg":"trace[1817503740] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"102.941593ms","start":"2026-04-20T10:19:44.083369Z","end":"2026-04-20T10:19:44.18631Z","steps":["trace[1817503740] 'process raft request' (duration: 23.374337ms)","trace[1817503740] 'compare' (duration: 79.2988ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:44.391448Z","caller":"traceutil/trace.go:171","msg":"trace[1980331140] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"200.81672ms","start":"2026-04-20T10:19:44.190602Z","end":"2026-04-20T10:19:44.391419Z","steps":["trace[1980331140] 'process raft request' (duration: 124.29661ms)","trace[1980331140] 'compare' (duration: 76.416668ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:44.541414Z","caller":"traceutil/trace.go:171","msg":"trace[1007065759] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"145.183961ms","start":"2026-04-20T10:19:44.396207Z","end":"2026-04-20T10:19:44.541391Z","steps":["trace[1007065759] 'process raft request' (duration: 140.655836ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:44.54207Z","caller":"traceutil/trace.go:171","msg":"trace[1078526293] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"145.716091ms","start":"2026-04-20T10:19:44.396338Z","end":"2026-04-20T10:19:44.542054Z","steps":["trace[1078526293] 'process raft request' (duration: 145.638659ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:44.542103Z","caller":"traceutil/trace.go:171","msg":"trace[2092314243] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"145.463766ms","start":"2026-04-20T10:19:44.396624Z","end":"2026-04-20T10:19:44.542088Z","steps":["trace[2092314243] 'process raft request' (duration: 145.388845ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:44.542085Z","caller":"traceutil/trace.go:171","msg":"trace[1812655686] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"145.799763ms","start":"2026-04-20T10:19:44.396252Z","end":"2026-04-20T10:19:44.542051Z","steps":["trace[1812655686] 'process raft request' (duration: 145.607589ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:44.542194Z","caller":"traceutil/trace.go:171","msg":"trace[1678976580] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"145.860034ms","start":"2026-04-20T10:19:44.396324Z","end":"2026-04-20T10:19:44.542184Z","steps":["trace[1678976580] 'process raft request' (duration: 145.61678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:44.754983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.715418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:44.755074Z","caller":"traceutil/trace.go:171","msg":"trace[1929778021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2225; }","duration":"102.904182ms","start":"2026-04-20T10:19:44.652149Z","end":"2026-04-20T10:19:44.755053Z","steps":["trace[1929778021] 'count revisions from in-memory index tree' (duration: 102.629987ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.304872Z","caller":"traceutil/trace.go:171","msg":"trace[2006470848] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"109.23733ms","start":"2026-04-20T10:19:46.195611Z","end":"2026-04-20T10:19:46.304849Z","steps":["trace[2006470848] 'process raft request' (duration: 109.086967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.66934Z","caller":"traceutil/trace.go:171","msg":"trace[1560125159] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"120.212835ms","start":"2026-04-20T10:19:46.54906Z","end":"2026-04-20T10:19:46.669273Z","steps":["trace[1560125159] 'process raft request' (duration: 120.069712ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.670909Z","caller":"traceutil/trace.go:171","msg":"trace[1338826083] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"120.748585ms","start":"2026-04-20T10:19:46.550143Z","end":"2026-04-20T10:19:46.670891Z","steps":["trace[1338826083] 'process raft request' (duration: 120.686984ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.671079Z","caller":"traceutil/trace.go:171","msg":"trace[539701635] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"119.830547ms","start":"2026-04-20T10:19:46.55124Z","end":"2026-04-20T10:19:46.671071Z","steps":["trace[539701635] 'process raft request' (duration: 119.625864ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.670936Z","caller":"traceutil/trace.go:171","msg":"trace[2138424301] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"121.366167ms","start":"2026-04-20T10:19:46.549557Z","end":"2026-04-20T10:19:46.670923Z","steps":["trace[2138424301] 'process raft request' (duration: 121.233324ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:46.670918Z","caller":"traceutil/trace.go:171","msg":"trace[653901415] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"121.422608ms","start":"2026-04-20T10:19:46.54947Z","end":"2026-04-20T10:19:46.670892Z","steps":["trace[653901415] 'process raft request' (duration: 121.211754ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:52.540287Z","caller":"traceutil/trace.go:171","msg":"trace[516022595] linearizableReadLoop","detail":"{readStateIndex:2352; appliedIndex:2351; }","duration":"112.259096ms","start":"2026-04-20T10:19:52.427993Z","end":"2026-04-20T10:19:52.540252Z","steps":["trace[516022595] 'read index received' (duration: 111.544273ms)","trace[516022595] 'applied index is now lower than readState.Index' (duration: 713.143µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:52.540405Z","caller":"traceutil/trace.go:171","msg":"trace[221083585] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"131.093007ms","start":"2026-04-20T10:19:52.409278Z","end":"2026-04-20T10:19:52.540371Z","steps":["trace[221083585] 'process raft request' (duration: 130.530607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:52.540487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.486431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:52.540562Z","caller":"traceutil/trace.go:171","msg":"trace[851033000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2261; }","duration":"112.612612ms","start":"2026-04-20T10:19:52.427932Z","end":"2026-04-20T10:19:52.540545Z","steps":["trace[851033000] 'agreement among raft nodes before linearized reading' (duration: 112.438609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.229367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.149033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:57.22959Z","caller":"traceutil/trace.go:171","msg":"trace[2110483398] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"778.950025ms","start":"2026-04-20T10:19:56.450617Z","end":"2026-04-20T10:19:57.229567Z","steps":["trace[2110483398] 'read index received' (duration: 175.480387ms)","trace[2110483398] 'applied index is now lower than readState.Index' (duration: 603.467128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:57.230005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.375684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:19:57.230061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.283273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:19:57.230091Z","caller":"traceutil/trace.go:171","msg":"trace[913987223] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2285; }","duration":"779.539726ms","start":"2026-04-20T10:19:56.450532Z","end":"2026-04-20T10:19:57.230072Z","steps":["trace[913987223] 'agreement among raft nodes before linearized reading' (duration: 779.402014ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:57.23011Z","caller":"traceutil/trace.go:171","msg":"trace[1706038815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2285; }","duration":"492.336374ms","start":"2026-04-20T10:19:56.73776Z","end":"2026-04-20T10:19:57.230097Z","steps":["trace[1706038815] 'agreement among raft nodes before linearized reading' (duration: 492.192251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.230142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.737741Z","time spent":"492.394205ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:19:57.230143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.450439Z","time spent":"779.69342ms","remote":"127.0.0.1:55998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:19:57.230235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.496764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:19:57.230271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.121907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:19:57.230313Z","caller":"traceutil/trace.go:171","msg":"trace[857230489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2285; }","duration":"472.620415ms","start":"2026-04-20T10:19:56.757675Z","end":"2026-04-20T10:19:57.230296Z","steps":["trace[857230489] 'agreement among raft nodes before linearized reading' (duration: 472.387951ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:57.230345Z","caller":"traceutil/trace.go:171","msg":"trace[181529343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2285; }","duration":"471.23009ms","start":"2026-04-20T10:19:56.759096Z","end":"2026-04-20T10:19:57.230326Z","steps":["trace[181529343] 'agreement among raft nodes before linearized reading' (duration: 471.000176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.230365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.85453ms","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-20T10:19:57.230362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.757668Z","time spent":"472.685557ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:19:57.230387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.759087Z","time spent":"471.292101ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:19:57.230401Z","caller":"traceutil/trace.go:171","msg":"trace[311410278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2285; }","duration":"472.945711ms","start":"2026-04-20T10:19:56.757446Z","end":"2026-04-20T10:19:57.230392Z","steps":["trace[311410278] 'agreement among raft nodes before linearized reading' (duration: 472.849329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.230267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.972873ms","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-20T10:19:57.230501Z","caller":"traceutil/trace.go:171","msg":"trace[206473306] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2285; }","duration":"472.220658ms","start":"2026-04-20T10:19:56.758258Z","end":"2026-04-20T10:19:57.230479Z","steps":["trace[206473306] 'agreement among raft nodes before linearized reading' (duration: 471.719649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.23043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.757432Z","time spent":"472.992033ms","remote":"127.0.0.1:55870","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-20T10:19:57.230638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.758249Z","time spent":"472.36457ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-20T10:19:57.23067Z","caller":"traceutil/trace.go:171","msg":"trace[1010622418] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"847.450094ms","start":"2026-04-20T10:19:56.382282Z","end":"2026-04-20T10:19:57.229732Z","steps":["trace[1010622418] 'process raft request' (duration: 243.828403ms)","trace[1010622418] 'compare' (duration: 602.903848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:57.230791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:56.382253Z","time spent":"848.469024ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:19:57.695118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.394966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:57.695437Z","caller":"traceutil/trace.go:171","msg":"trace[435068014] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"460.627222ms","start":"2026-04-20T10:19:57.234789Z","end":"2026-04-20T10:19:57.695416Z","steps":["trace[435068014] 'process raft request' (duration: 224.812659ms)","trace[435068014] 'compare' (duration: 235.151811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:57.695517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.234772Z","time spent":"460.711003ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:19:57.695765Z","caller":"traceutil/trace.go:171","msg":"trace[564424256] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"460.898237ms","start":"2026-04-20T10:19:57.234848Z","end":"2026-04-20T10:19:57.695746Z","steps":["trace[564424256] 'process raft request' (duration: 460.365497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:57.695982Z","caller":"traceutil/trace.go:171","msg":"trace[1166772946] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2378; }","duration":"447.365794ms","start":"2026-04-20T10:19:57.2486Z","end":"2026-04-20T10:19:57.695966Z","steps":["trace[1166772946] 'read index received' (duration: 7.309867ms)","trace[1166772946] 'applied index is now lower than readState.Index' (duration: 440.055117ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:57.696079Z","caller":"traceutil/trace.go:171","msg":"trace[1006914013] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"460.271806ms","start":"2026-04-20T10:19:57.235797Z","end":"2026-04-20T10:19:57.696068Z","steps":["trace[1006914013] 'process raft request' (duration: 460.061841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:57.696041Z","caller":"traceutil/trace.go:171","msg":"trace[1894029690] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"460.570519ms","start":"2026-04-20T10:19:57.235431Z","end":"2026-04-20T10:19:57.696002Z","steps":["trace[1894029690] 'process raft request' (duration: 460.214873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.696224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.923609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:57.696545Z","caller":"traceutil/trace.go:171","msg":"trace[1468508517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2290; }","duration":"272.329716ms","start":"2026-04-20T10:19:57.424192Z","end":"2026-04-20T10:19:57.696521Z","steps":["trace[1468508517] 'agreement among raft nodes before linearized reading' (duration: 271.904348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.696272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.65018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:57.696256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.234844Z","time spent":"460.968688ms","remote":"127.0.0.1:55870","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-20T10:19:57.697015Z","caller":"traceutil/trace.go:171","msg":"trace[826982279] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2290; }","duration":"448.463854ms","start":"2026-04-20T10:19:57.248537Z","end":"2026-04-20T10:19:57.697001Z","steps":["trace[826982279] 'agreement among raft nodes before linearized reading' (duration: 447.66025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.697158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.248525Z","time spent":"448.614097ms","remote":"127.0.0.1:55732","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-20T10:19:57.696318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.46248ms","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-20T10:19:57.6974Z","caller":"traceutil/trace.go:171","msg":"trace[1591915879] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2290; }","duration":"151.56724ms","start":"2026-04-20T10:19:57.545808Z","end":"2026-04-20T10:19:57.697375Z","steps":["trace[1591915879] 'agreement among raft nodes before linearized reading' (duration: 150.420899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:57.696384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.235416Z","time spent":"460.812765ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:19:57.696151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.235777Z","time spent":"460.330976ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:19:58.477335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.446316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:58.477434Z","caller":"traceutil/trace.go:171","msg":"trace[1494138813] linearizableReadLoop","detail":"{readStateIndex:2383; appliedIndex:2382; }","duration":"644.076046ms","start":"2026-04-20T10:19:57.833339Z","end":"2026-04-20T10:19:58.477415Z","steps":["trace[1494138813] 'read index received' (duration: 40.02µs)","trace[1494138813] 'applied index is now lower than readState.Index' (duration: 644.034696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:58.47782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.460674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:58.478013Z","caller":"traceutil/trace.go:171","msg":"trace[813643567] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"167.518938ms","start":"2026-04-20T10:19:58.310482Z","end":"2026-04-20T10:19:58.478Z","steps":["trace[813643567] 'process raft request' (duration: 167.326955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:58.478022Z","caller":"traceutil/trace.go:171","msg":"trace[1712889395] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2291; }","duration":"644.710838ms","start":"2026-04-20T10:19:57.83329Z","end":"2026-04-20T10:19:58.478001Z","steps":["trace[1712889395] 'agreement among raft nodes before linearized reading' (duration: 644.438573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:58.478222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.83327Z","time spent":"644.934673ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:19:58.477936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.164901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:19:58.478472Z","caller":"traceutil/trace.go:171","msg":"trace[1274869774] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2292; }","duration":"627.729671ms","start":"2026-04-20T10:19:57.85073Z","end":"2026-04-20T10:19:58.47846Z","steps":["trace[1274869774] 'agreement among raft nodes before linearized reading' (duration: 627.144491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:58.478521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.850719Z","time spent":"627.787002ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:19:58.478785Z","caller":"traceutil/trace.go:171","msg":"trace[672800557] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"776.408199ms","start":"2026-04-20T10:19:57.702364Z","end":"2026-04-20T10:19:58.478772Z","steps":["trace[672800557] 'process raft request' (duration: 101.450654ms)","trace[672800557] 'compare' (duration: 673.326023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:58.478857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:57.702341Z","time spent":"776.47915ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:19:59.380884Z","caller":"traceutil/trace.go:171","msg":"trace[1094492627] linearizableReadLoop","detail":"{readStateIndex:2389; appliedIndex:2388; }","duration":"120.96785ms","start":"2026-04-20T10:19:59.259894Z","end":"2026-04-20T10:19:59.380862Z","steps":["trace[1094492627] 'read index received' (duration: 120.753196ms)","trace[1094492627] 'applied index is now lower than readState.Index' (duration: 213.254µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:59.38113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.219304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:19:59.381172Z","caller":"traceutil/trace.go:171","msg":"trace[1283603278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2297; }","duration":"121.304215ms","start":"2026-04-20T10:19:59.259858Z","end":"2026-04-20T10:19:59.381162Z","steps":["trace[1283603278] 'agreement among raft nodes before linearized reading' (duration: 121.142122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:59.381222Z","caller":"traceutil/trace.go:171","msg":"trace[1897923076] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"141.022504ms","start":"2026-04-20T10:19:59.240171Z","end":"2026-04-20T10:19:59.381193Z","steps":["trace[1897923076] 'process raft request' (duration: 140.535125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:59.562127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.045697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:59.562519Z","caller":"traceutil/trace.go:171","msg":"trace[1874047340] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"177.307402ms","start":"2026-04-20T10:19:59.385193Z","end":"2026-04-20T10:19:59.5625Z","steps":["trace[1874047340] 'process raft request' (duration: 45.812286ms)","trace[1874047340] 'compare' (duration: 130.940055ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:59.562229Z","caller":"traceutil/trace.go:171","msg":"trace[1441523107] linearizableReadLoop","detail":"{readStateIndex:2390; appliedIndex:2389; }","duration":"146.305082ms","start":"2026-04-20T10:19:59.415908Z","end":"2026-04-20T10:19:59.562213Z","steps":["trace[1441523107] 'read index received' (duration: 15.025831ms)","trace[1441523107] 'applied index is now lower than readState.Index' (duration: 131.278211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:59.562939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.856616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:59.562998Z","caller":"traceutil/trace.go:171","msg":"trace[163105551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2298; }","duration":"136.931727ms","start":"2026-04-20T10:19:59.426053Z","end":"2026-04-20T10:19:59.562984Z","steps":["trace[163105551] 'agreement among raft nodes before linearized reading' (duration: 136.830606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:59.563071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.387083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:3015"} {"level":"warn","ts":"2026-04-20T10:19:59.562945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.016065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-20T10:19:59.563164Z","caller":"traceutil/trace.go:171","msg":"trace[1817614207] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2298; }","duration":"101.523295ms","start":"2026-04-20T10:19:59.461622Z","end":"2026-04-20T10:19:59.563145Z","steps":["trace[1817614207] 'agreement among raft nodes before linearized reading' (duration: 101.278821ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:59.563181Z","caller":"traceutil/trace.go:171","msg":"trace[1563124673] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2298; }","duration":"147.28548ms","start":"2026-04-20T10:19:59.415872Z","end":"2026-04-20T10:19:59.563157Z","steps":["trace[1563124673] 'agreement among raft nodes before linearized reading' (duration: 146.69363ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:59.929632Z","caller":"traceutil/trace.go:171","msg":"trace[1901493857] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"224.500653ms","start":"2026-04-20T10:19:59.705106Z","end":"2026-04-20T10:19:59.929607Z","steps":["trace[1901493857] 'process raft request' (duration: 224.422621ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:59.929664Z","caller":"traceutil/trace.go:171","msg":"trace[422119552] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"224.89247ms","start":"2026-04-20T10:19:59.704726Z","end":"2026-04-20T10:19:59.929619Z","steps":["trace[422119552] 'process raft request' (duration: 194.184846ms)","trace[422119552] 'compare' (duration: 30.48214ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:59.929785Z","caller":"traceutil/trace.go:171","msg":"trace[680762088] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"222.678328ms","start":"2026-04-20T10:19:59.707088Z","end":"2026-04-20T10:19:59.929766Z","steps":["trace[680762088] 'process raft request' (duration: 222.485565ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:05.618765Z","caller":"traceutil/trace.go:171","msg":"trace[2077921328] linearizableReadLoop","detail":"{readStateIndex:2420; appliedIndex:2419; }","duration":"128.51764ms","start":"2026-04-20T10:20:05.490222Z","end":"2026-04-20T10:20:05.61874Z","steps":["trace[2077921328] 'read index received' (duration: 110.608695ms)","trace[2077921328] 'applied index is now lower than readState.Index' (duration: 17.907685ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:05.619108Z","caller":"traceutil/trace.go:171","msg":"trace[697369987] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"165.289817ms","start":"2026-04-20T10:20:05.453797Z","end":"2026-04-20T10:20:05.619087Z","steps":["trace[697369987] 'process raft request' (duration: 147.047966ms)","trace[697369987] 'compare' (duration: 17.773812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:05.619295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.05656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T10:20:05.619356Z","caller":"traceutil/trace.go:171","msg":"trace[1548740991] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:2327; }","duration":"129.160732ms","start":"2026-04-20T10:20:05.490179Z","end":"2026-04-20T10:20:05.61934Z","steps":["trace[1548740991] 'agreement among raft nodes before linearized reading' (duration: 128.965239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:05.904659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.137135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:20:05.904857Z","caller":"traceutil/trace.go:171","msg":"trace[1079914028] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"281.794362ms","start":"2026-04-20T10:20:05.623005Z","end":"2026-04-20T10:20:05.904799Z","steps":["trace[1079914028] 'process raft request' (duration: 117.345691ms)","trace[1079914028] 'compare' (duration: 163.940061ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:05.905052Z","caller":"traceutil/trace.go:171","msg":"trace[1542986305] linearizableReadLoop","detail":"{readStateIndex:2423; appliedIndex:2421; }","duration":"167.266924ms","start":"2026-04-20T10:20:05.737757Z","end":"2026-04-20T10:20:05.905023Z","steps":["trace[1542986305] 'read index received' (duration: 2.600998ms)","trace[1542986305] 'applied index is now lower than readState.Index' (duration: 164.664955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:05.905285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.527509ms","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-20T10:20:05.905326Z","caller":"traceutil/trace.go:171","msg":"trace[1040810512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2329; }","duration":"167.62955ms","start":"2026-04-20T10:20:05.737686Z","end":"2026-04-20T10:20:05.905316Z","steps":["trace[1040810512] 'agreement among raft nodes before linearized reading' (duration: 167.442206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:05.905469Z","caller":"traceutil/trace.go:171","msg":"trace[1831176206] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"268.696368ms","start":"2026-04-20T10:20:05.636733Z","end":"2026-04-20T10:20:05.90543Z","steps":["trace[1831176206] 'process raft request' (duration: 268.127787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.425391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.579173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:20:06.425515Z","caller":"traceutil/trace.go:171","msg":"trace[1098613373] linearizableReadLoop","detail":"{readStateIndex:2425; appliedIndex:2424; }","duration":"431.706791ms","start":"2026-04-20T10:20:05.993791Z","end":"2026-04-20T10:20:06.425498Z","steps":["trace[1098613373] 'read index received' (duration: 198.839893ms)","trace[1098613373] 'applied index is now lower than readState.Index' (duration: 232.865358ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:06.425807Z","caller":"traceutil/trace.go:171","msg":"trace[582685275] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"436.761636ms","start":"2026-04-20T10:20:05.988919Z","end":"2026-04-20T10:20:06.425681Z","steps":["trace[582685275] 'process raft request' (duration: 203.784056ms)","trace[582685275] 'compare' (duration: 232.302418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:06.425884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.064917ms","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-20T10:20:06.425977Z","caller":"traceutil/trace.go:171","msg":"trace[1050766311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2331; }","duration":"432.264932ms","start":"2026-04-20T10:20:05.993691Z","end":"2026-04-20T10:20:06.425955Z","steps":["trace[1050766311] 'agreement among raft nodes before linearized reading' (duration: 432.000346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.426029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:05.993669Z","time spent":"432.351083ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:20:06.425911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:05.9889Z","time spent":"436.966969ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:20:06.801615Z","caller":"traceutil/trace.go:171","msg":"trace[1377294254] linearizableReadLoop","detail":"{readStateIndex:2426; appliedIndex:2425; }","duration":"376.050203ms","start":"2026-04-20T10:20:06.42554Z","end":"2026-04-20T10:20:06.801591Z","steps":["trace[1377294254] 'read index received' (duration: 375.88362ms)","trace[1377294254] 'applied index is now lower than readState.Index' (duration: 165.543µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:06.801753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.050381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:7202"} {"level":"info","ts":"2026-04-20T10:20:06.801755Z","caller":"traceutil/trace.go:171","msg":"trace[122443502] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"565.052353ms","start":"2026-04-20T10:20:06.236672Z","end":"2026-04-20T10:20:06.801724Z","steps":["trace[122443502] 'process raft request' (duration: 564.778018ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:06.801829Z","caller":"traceutil/trace.go:171","msg":"trace[143264886] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:2332; }","duration":"649.179052ms","start":"2026-04-20T10:20:06.152634Z","end":"2026-04-20T10:20:06.801814Z","steps":["trace[143264886] 'agreement among raft nodes before linearized reading' (duration: 649.03112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.801889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.152613Z","time spent":"649.266164ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":7224,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 "} {"level":"warn","ts":"2026-04-20T10:20:06.801908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.627892ms","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-20T10:20:06.80196Z","caller":"traceutil/trace.go:171","msg":"trace[1660522891] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2332; }","duration":"791.706804ms","start":"2026-04-20T10:20:06.010239Z","end":"2026-04-20T10:20:06.801946Z","steps":["trace[1660522891] 'agreement among raft nodes before linearized reading' (duration: 791.52907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.802014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.010227Z","time spent":"791.779025ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-20T10:20:06.80205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.236646Z","time spent":"565.186645ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7188,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:20:06.802959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.297393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:500 ","response":"range_response_count:70 size:67566"} {"level":"info","ts":"2026-04-20T10:20:06.804544Z","caller":"traceutil/trace.go:171","msg":"trace[2027016412] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:70; response_revision:2332; }","duration":"651.406074ms","start":"2026-04-20T10:20:06.152493Z","end":"2026-04-20T10:20:06.803899Z","steps":["trace[2027016412] 'agreement among raft nodes before linearized reading' (duration: 649.222993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.804641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.152472Z","time spent":"652.140217ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":70,"response size":67588,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:500 "} {"level":"info","ts":"2026-04-20T10:20:06.842681Z","caller":"traceutil/trace.go:171","msg":"trace[404869688] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"311.597198ms","start":"2026-04-20T10:20:06.531061Z","end":"2026-04-20T10:20:06.842658Z","steps":["trace[404869688] 'process raft request' (duration: 311.490816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.8427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.937237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:06.842698Z","caller":"traceutil/trace.go:171","msg":"trace[1996049649] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"412.926291ms","start":"2026-04-20T10:20:06.429742Z","end":"2026-04-20T10:20:06.842668Z","steps":["trace[1996049649] 'process raft request' (duration: 412.724557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:06.842742Z","caller":"traceutil/trace.go:171","msg":"trace[123446615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2334; }","duration":"416.029499ms","start":"2026-04-20T10:20:06.426702Z","end":"2026-04-20T10:20:06.842732Z","steps":["trace[123446615] 'agreement among raft nodes before linearized reading' (duration: 415.891647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:06.842765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.426684Z","time spent":"416.075649ms","remote":"127.0.0.1:55560","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-20T10:20:06.842783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.531037Z","time spent":"311.698ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:20:06.842825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.429724Z","time spent":"413.029662ms","remote":"127.0.0.1:55870","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-20T10:20:07.154243Z","caller":"traceutil/trace.go:171","msg":"trace[957006375] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"346.860607ms","start":"2026-04-20T10:20:06.807353Z","end":"2026-04-20T10:20:07.154213Z","steps":["trace[957006375] 'process raft request' (duration: 262.507821ms)","trace[957006375] 'compare' (duration: 84.07201ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:07.154325Z","caller":"traceutil/trace.go:171","msg":"trace[1511094003] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"286.978538ms","start":"2026-04-20T10:20:06.867323Z","end":"2026-04-20T10:20:07.154302Z","steps":["trace[1511094003] 'process raft request' (duration: 286.906687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.154391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.807334Z","time spent":"346.98633ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:20:07.154441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.731103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:07.154484Z","caller":"traceutil/trace.go:171","msg":"trace[558877383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2336; }","duration":"310.810814ms","start":"2026-04-20T10:20:06.843663Z","end":"2026-04-20T10:20:07.154473Z","steps":["trace[558877383] 'agreement among raft nodes before linearized reading' (duration: 310.713492ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:07.15434Z","caller":"traceutil/trace.go:171","msg":"trace[1626177934] linearizableReadLoop","detail":"{readStateIndex:2429; appliedIndex:2428; }","duration":"310.620841ms","start":"2026-04-20T10:20:06.843698Z","end":"2026-04-20T10:20:07.154319Z","steps":["trace[1626177934] 'read index received' (duration: 226.262166ms)","trace[1626177934] 'applied index is now lower than readState.Index' (duration: 84.357175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:07.154515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:06.84365Z","time spent":"310.858334ms","remote":"127.0.0.1:55574","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-20T10:20:07.154916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.391709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6950"} {"level":"warn","ts":"2026-04-20T10:20:07.154948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.691961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-04-20T10:20:07.154964Z","caller":"traceutil/trace.go:171","msg":"trace[1650163444] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2336; }","duration":"232.473441ms","start":"2026-04-20T10:20:06.922479Z","end":"2026-04-20T10:20:07.154953Z","steps":["trace[1650163444] 'agreement among raft nodes before linearized reading' (duration: 232.39959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:07.155007Z","caller":"traceutil/trace.go:171","msg":"trace[720578471] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:2336; }","duration":"235.786683ms","start":"2026-04-20T10:20:06.919204Z","end":"2026-04-20T10:20:07.15499Z","steps":["trace[720578471] 'agreement among raft nodes before linearized reading' (duration: 235.678021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.155037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.694668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:1 size:1893"} {"level":"info","ts":"2026-04-20T10:20:07.155089Z","caller":"traceutil/trace.go:171","msg":"trace[524879900] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:1; response_revision:2336; }","duration":"101.777359ms","start":"2026-04-20T10:20:07.053299Z","end":"2026-04-20T10:20:07.155076Z","steps":["trace[524879900] 'agreement among raft nodes before linearized reading' (duration: 101.595456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.155319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.95441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" limit:500 ","response":"range_response_count:55 size:40025"} {"level":"info","ts":"2026-04-20T10:20:07.155369Z","caller":"traceutil/trace.go:171","msg":"trace[123833173] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:55; response_revision:2336; }","duration":"270.042932ms","start":"2026-04-20T10:20:06.885311Z","end":"2026-04-20T10:20:07.155354Z","steps":["trace[123833173] 'agreement among raft nodes before linearized reading' (duration: 269.515242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.451898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.202199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-04-20T10:20:07.452001Z","caller":"traceutil/trace.go:171","msg":"trace[307179469] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:2337; }","duration":"191.347532ms","start":"2026-04-20T10:20:07.26063Z","end":"2026-04-20T10:20:07.451977Z","steps":["trace[307179469] 'range keys from in-memory index tree' (duration: 191.005566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.452426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.98691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:979"} {"level":"info","ts":"2026-04-20T10:20:07.45249Z","caller":"traceutil/trace.go:171","msg":"trace[1546425302] range","detail":"{range_begin:/registry/clusterroles/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2337; }","duration":"128.084512ms","start":"2026-04-20T10:20:07.324392Z","end":"2026-04-20T10:20:07.452476Z","steps":["trace[1546425302] 'range keys from in-memory index tree' (duration: 127.167084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:07.452595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.302392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" limit:500 ","response":"range_response_count:171 size:130516"} {"level":"info","ts":"2026-04-20T10:20:07.452665Z","caller":"traceutil/trace.go:171","msg":"trace[1323011923] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:171; response_revision:2337; }","duration":"191.392774ms","start":"2026-04-20T10:20:07.261257Z","end":"2026-04-20T10:20:07.45265Z","steps":["trace[1323011923] 'range keys from in-memory index tree' (duration: 190.404876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:08.243342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.9504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-04-20T10:20:08.243437Z","caller":"traceutil/trace.go:171","msg":"trace[13002748] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:2340; }","duration":"105.078232ms","start":"2026-04-20T10:20:08.138339Z","end":"2026-04-20T10:20:08.243417Z","steps":["trace[13002748] 'range keys from in-memory index tree' (duration: 104.873348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:25.470754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.270511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:757"} {"level":"info","ts":"2026-04-20T10:20:25.47085Z","caller":"traceutil/trace.go:171","msg":"trace[246794478] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:2420; }","duration":"124.409483ms","start":"2026-04-20T10:20:25.346421Z","end":"2026-04-20T10:20:25.47083Z","steps":["trace[246794478] 'range keys from in-memory index tree' (duration: 124.048177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:25.470909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.09437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:941"} {"level":"info","ts":"2026-04-20T10:20:25.471015Z","caller":"traceutil/trace.go:171","msg":"trace[1122278431] range","detail":"{range_begin:/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2420; }","duration":"153.240892ms","start":"2026-04-20T10:20:25.317744Z","end":"2026-04-20T10:20:25.470985Z","steps":["trace[1122278431] 'range keys from in-memory index tree' (duration: 152.878806ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:33.192056Z","caller":"traceutil/trace.go:171","msg":"trace[721255591] linearizableReadLoop","detail":"{readStateIndex:2554; appliedIndex:2553; }","duration":"132.216248ms","start":"2026-04-20T10:20:33.059813Z","end":"2026-04-20T10:20:33.192029Z","steps":["trace[721255591] 'read index received' (duration: 37.49025ms)","trace[721255591] 'applied index is now lower than readState.Index' (duration: 94.724558ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:33.192146Z","caller":"traceutil/trace.go:171","msg":"trace[1948291230] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"160.403285ms","start":"2026-04-20T10:20:33.0317Z","end":"2026-04-20T10:20:33.192103Z","steps":["trace[1948291230] 'process raft request' (duration: 65.677156ms)","trace[1948291230] 'compare' (duration: 94.525155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:33.192247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.415703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"info","ts":"2026-04-20T10:20:33.192302Z","caller":"traceutil/trace.go:171","msg":"trace[887630353] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:2455; }","duration":"132.531095ms","start":"2026-04-20T10:20:33.059761Z","end":"2026-04-20T10:20:33.192292Z","steps":["trace[887630353] 'agreement among raft nodes before linearized reading' (duration: 132.416412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:33.192318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.683746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-20T10:20:33.192398Z","caller":"traceutil/trace.go:171","msg":"trace[1795072748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:2455; }","duration":"103.800949ms","start":"2026-04-20T10:20:33.088578Z","end":"2026-04-20T10:20:33.192379Z","steps":["trace[1795072748] 'agreement among raft nodes before linearized reading' (duration: 103.668076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:33.802466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.126997ms","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:526"} {"level":"info","ts":"2026-04-20T10:20:33.802652Z","caller":"traceutil/trace.go:171","msg":"trace[1636268624] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2457; }","duration":"141.334171ms","start":"2026-04-20T10:20:33.661283Z","end":"2026-04-20T10:20:33.802617Z","steps":["trace[1636268624] 'range keys from in-memory index tree' (duration: 140.966713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:33.803152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.609709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:101670"} {"level":"info","ts":"2026-04-20T10:20:33.803232Z","caller":"traceutil/trace.go:171","msg":"trace[1589448751] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:2457; }","duration":"126.732071ms","start":"2026-04-20T10:20:33.676484Z","end":"2026-04-20T10:20:33.803216Z","steps":["trace[1589448751] 'range keys from in-memory index tree' (duration: 125.953623ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:34.052884Z","caller":"traceutil/trace.go:171","msg":"trace[1406016269] linearizableReadLoop","detail":"{readStateIndex:2558; appliedIndex:2557; }","duration":"152.956742ms","start":"2026-04-20T10:20:33.899906Z","end":"2026-04-20T10:20:34.052863Z","steps":["trace[1406016269] 'read index received' (duration: 53.341704ms)","trace[1406016269] 'applied index is now lower than readState.Index' (duration: 99.613928ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:34.052913Z","caller":"traceutil/trace.go:171","msg":"trace[1757232342] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"155.696778ms","start":"2026-04-20T10:20:33.897181Z","end":"2026-04-20T10:20:34.052878Z","steps":["trace[1757232342] 'process raft request' (duration: 56.150712ms)","trace[1757232342] 'compare' (duration: 99.350091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:34.053169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.246218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-04-20T10:20:34.053225Z","caller":"traceutil/trace.go:171","msg":"trace[164117556] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2459; }","duration":"153.31866ms","start":"2026-04-20T10:20:33.899893Z","end":"2026-04-20T10:20:34.053212Z","steps":["trace[164117556] 'agreement among raft nodes before linearized reading' (duration: 153.050584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:34.509398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.011931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:20:34.509616Z","caller":"traceutil/trace.go:171","msg":"trace[1753461189] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"180.948996ms","start":"2026-04-20T10:20:34.328639Z","end":"2026-04-20T10:20:34.509588Z","steps":["trace[1753461189] 'process raft request' (duration: 67.623948ms)","trace[1753461189] 'compare' (duration: 112.762245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:34.993758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.164292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-20T10:20:34.993856Z","caller":"traceutil/trace.go:171","msg":"trace[81668220] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2461; }","duration":"267.311796ms","start":"2026-04-20T10:20:34.726521Z","end":"2026-04-20T10:20:34.993833Z","steps":["trace[81668220] 'range keys from in-memory index tree' (duration: 266.996368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:34.994035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.368788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:20:34.994211Z","caller":"traceutil/trace.go:171","msg":"trace[214555939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2461; }","duration":"368.525992ms","start":"2026-04-20T10:20:34.625592Z","end":"2026-04-20T10:20:34.994118Z","steps":["trace[214555939] 'range keys from in-memory index tree' (duration: 368.172964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:34.994456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:20:34.625568Z","time spent":"368.794709ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:20:35.591341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.891226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:35.591427Z","caller":"traceutil/trace.go:171","msg":"trace[228746576] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2466; }","duration":"110.03314ms","start":"2026-04-20T10:20:35.481374Z","end":"2026-04-20T10:20:35.591407Z","steps":["trace[228746576] 'count revisions from in-memory index tree' (duration: 109.785223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:35.59165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.246863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T10:20:35.591834Z","caller":"traceutil/trace.go:171","msg":"trace[1474148378] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:2466; }","duration":"100.464928ms","start":"2026-04-20T10:20:35.49135Z","end":"2026-04-20T10:20:35.591815Z","steps":["trace[1474148378] 'range keys from in-memory index tree' (duration: 100.11086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:35.800169Z","caller":"traceutil/trace.go:171","msg":"trace[2076381443] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"133.884913ms","start":"2026-04-20T10:20:35.666263Z","end":"2026-04-20T10:20:35.800148Z","steps":["trace[2076381443] 'process raft request' (duration: 129.62713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:36.029963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.909789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:36.030044Z","caller":"traceutil/trace.go:171","msg":"trace[1204280853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2468; }","duration":"128.042522ms","start":"2026-04-20T10:20:35.901982Z","end":"2026-04-20T10:20:36.030025Z","steps":["trace[1204280853] 'range keys from in-memory index tree' (duration: 127.830707ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:37.143188Z","caller":"traceutil/trace.go:171","msg":"trace[662316272] linearizableReadLoop","detail":"{readStateIndex:2573; appliedIndex:2572; }","duration":"100.985691ms","start":"2026-04-20T10:20:37.042184Z","end":"2026-04-20T10:20:37.143169Z","steps":["trace[662316272] 'read index received' (duration: 43.19622ms)","trace[662316272] 'applied index is now lower than readState.Index' (duration: 57.788231ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:37.143224Z","caller":"traceutil/trace.go:171","msg":"trace[679719535] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"108.570933ms","start":"2026-04-20T10:20:37.03463Z","end":"2026-04-20T10:20:37.143201Z","steps":["trace[679719535] 'process raft request' (duration: 50.788662ms)","trace[679719535] 'compare' (duration: 57.523345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:37.143534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.32368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-lx5bv\" ","response":"range_response_count:1 size:11386"} {"level":"info","ts":"2026-04-20T10:20:37.143682Z","caller":"traceutil/trace.go:171","msg":"trace[1604519906] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-lx5bv; range_end:; response_count:1; response_revision:2473; }","duration":"101.528545ms","start":"2026-04-20T10:20:37.04213Z","end":"2026-04-20T10:20:37.143659Z","steps":["trace[1604519906] 'agreement among raft nodes before linearized reading' (duration: 101.170745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:37.389488Z","caller":"traceutil/trace.go:171","msg":"trace[1736299053] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"241.143165ms","start":"2026-04-20T10:20:37.148307Z","end":"2026-04-20T10:20:37.38945Z","steps":["trace[1736299053] 'process raft request' (duration: 193.719893ms)","trace[1736299053] 'compare' (duration: 47.255498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:37.389738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.97067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-lx5bv\" ","response":"range_response_count:1 size:11386"} {"level":"info","ts":"2026-04-20T10:20:37.38982Z","caller":"traceutil/trace.go:171","msg":"trace[141116096] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-lx5bv; range_end:; response_count:1; response_revision:2474; }","duration":"236.095424ms","start":"2026-04-20T10:20:37.153713Z","end":"2026-04-20T10:20:37.389808Z","steps":["trace[141116096] 'agreement among raft nodes before linearized reading' (duration: 235.808987ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:37.389468Z","caller":"traceutil/trace.go:171","msg":"trace[488093010] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"235.680584ms","start":"2026-04-20T10:20:37.153743Z","end":"2026-04-20T10:20:37.389424Z","steps":["trace[488093010] 'read index received' (duration: 188.227511ms)","trace[488093010] 'applied index is now lower than readState.Index' (duration: 47.451833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:37.738268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.166925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dmc4f\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-04-20T10:20:37.738346Z","caller":"traceutil/trace.go:171","msg":"trace[174186685] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dmc4f; range_end:; response_count:1; response_revision:2475; }","duration":"187.293838ms","start":"2026-04-20T10:20:37.551033Z","end":"2026-04-20T10:20:37.738327Z","steps":["trace[174186685] 'range keys from in-memory index tree' (duration: 186.9385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:38.225611Z","caller":"traceutil/trace.go:171","msg":"trace[1949849907] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"133.908883ms","start":"2026-04-20T10:20:38.091674Z","end":"2026-04-20T10:20:38.225583Z","steps":["trace[1949849907] 'process raft request' (duration: 133.709748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:39.083646Z","caller":"traceutil/trace.go:171","msg":"trace[1133510771] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"180.742261ms","start":"2026-04-20T10:20:38.902811Z","end":"2026-04-20T10:20:39.083554Z","steps":["trace[1133510771] 'process raft request' (duration: 180.450044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:39.352398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.432499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dmc4f\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-04-20T10:20:39.352501Z","caller":"traceutil/trace.go:171","msg":"trace[829207974] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dmc4f; range_end:; response_count:1; response_revision:2481; }","duration":"260.583413ms","start":"2026-04-20T10:20:39.091894Z","end":"2026-04-20T10:20:39.352478Z","steps":["trace[829207974] 'range keys from in-memory index tree' (duration: 260.192824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:39.352618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.728553ms","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-20T10:20:39.352694Z","caller":"traceutil/trace.go:171","msg":"trace[911489684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2481; }","duration":"205.844536ms","start":"2026-04-20T10:20:39.14683Z","end":"2026-04-20T10:20:39.352675Z","steps":["trace[911489684] 'range keys from in-memory index tree' (duration: 205.566449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:39.352715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.812268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:39.352778Z","caller":"traceutil/trace.go:171","msg":"trace[2109606409] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2481; }","duration":"133.196197ms","start":"2026-04-20T10:20:39.219561Z","end":"2026-04-20T10:20:39.352758Z","steps":["trace[2109606409] 'count revisions from in-memory index tree' (duration: 132.439498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:40.0033Z","caller":"traceutil/trace.go:171","msg":"trace[1667214595] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"105.608642ms","start":"2026-04-20T10:20:39.89766Z","end":"2026-04-20T10:20:40.003269Z","steps":["trace[1667214595] 'process raft request' (duration: 105.410287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:40.241078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.786103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2z4b8\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-20T10:20:40.241167Z","caller":"traceutil/trace.go:171","msg":"trace[1135692656] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2z4b8; range_end:; response_count:1; response_revision:2486; }","duration":"141.898226ms","start":"2026-04-20T10:20:40.099241Z","end":"2026-04-20T10:20:40.241139Z","steps":["trace[1135692656] 'range keys from in-memory index tree' (duration: 141.603529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:40.504079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.59139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:20:40.504152Z","caller":"traceutil/trace.go:171","msg":"trace[616921516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2487; }","duration":"107.719513ms","start":"2026-04-20T10:20:40.396417Z","end":"2026-04-20T10:20:40.504136Z","steps":["trace[616921516] 'range keys from in-memory index tree' (duration: 107.396446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:40.739657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.056579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:20:40.739799Z","caller":"traceutil/trace.go:171","msg":"trace[1064745827] linearizableReadLoop","detail":"{readStateIndex:2589; appliedIndex:2588; }","duration":"135.615125ms","start":"2026-04-20T10:20:40.604135Z","end":"2026-04-20T10:20:40.73975Z","steps":["trace[1064745827] 'read index received' (duration: 8.28079ms)","trace[1064745827] 'applied index is now lower than readState.Index' (duration: 127.333255ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:40.739809Z","caller":"traceutil/trace.go:171","msg":"trace[943671983] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"160.085744ms","start":"2026-04-20T10:20:40.579694Z","end":"2026-04-20T10:20:40.739779Z","steps":["trace[943671983] 'process raft request' (duration: 32.81085ms)","trace[943671983] 'compare' (duration: 126.933136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:20:40.739956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.82184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2z4b8\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-20T10:20:40.740006Z","caller":"traceutil/trace.go:171","msg":"trace[1749812624] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2z4b8; range_end:; response_count:1; response_revision:2489; }","duration":"135.895381ms","start":"2026-04-20T10:20:40.604101Z","end":"2026-04-20T10:20:40.739996Z","steps":["trace[1749812624] 'agreement among raft nodes before linearized reading' (duration: 135.752188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:42.782878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.0767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-9dn2g\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-04-20T10:20:42.783063Z","caller":"traceutil/trace.go:171","msg":"trace[1086238063] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-9dn2g; range_end:; response_count:1; response_revision:2496; }","duration":"100.289494ms","start":"2026-04-20T10:20:42.682749Z","end":"2026-04-20T10:20:42.783038Z","steps":["trace[1086238063] 'range keys from in-memory index tree' (duration: 99.958806ms)"],"step_count":1}