{"level":"warn","ts":"2026-03-17T21:55:50.605519Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:50.605725Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.199: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.199:2380","--initial-cluster=instance=https://199.19.213.199:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.199:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.199: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-03-17T21:55:50.605944Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:50.605962Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.199:2380"]} {"level":"info","ts":"2026-03-17T21:55:50.605985Z","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-03-17T21:55:50.606797Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"]} {"level":"info","ts":"2026-03-17T21:55:50.606898Z","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.199:2380"],"listen-peer-urls":["https://199.19.213.199:2380"],"advertise-client-urls":["https://199.19.213.199:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.199: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-03-17T21:55:50.625041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.889327ms"} {"level":"info","ts":"2026-03-17T21:55:50.680282Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d660142aa1b7ce9","cluster-id":"6964c025ad43a005"} {"level":"info","ts":"2026-03-17T21:55:50.680426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:55:50.680466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became follower at term 0"} {"level":"info","ts":"2026-03-17T21:55:50.680481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d660142aa1b7ce9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:55:50.680495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became follower at term 1"} {"level":"info","ts":"2026-03-17T21:55:50.680551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=(7882989583592029417)"} {"level":"warn","ts":"2026-03-17T21:55:50.780256Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:55:50.783509Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:55:50.786677Z","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-03-17T21:55:50.797848Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d660142aa1b7ce9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:55:50.798581Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d660142aa1b7ce9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:55:50.798752Z","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-03-17T21:55:50.798837Z","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-03-17T21:55:50.798851Z","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-03-17T21:55:50.804194Z","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-03-17T21:55:50.804492Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.199:2380"} {"level":"info","ts":"2026-03-17T21:55:50.804562Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.199:2380"} {"level":"info","ts":"2026-03-17T21:55:50.804542Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d660142aa1b7ce9","initial-advertise-peer-urls":["https://199.19.213.199:2380"],"listen-peer-urls":["https://199.19.213.199:2380"],"advertise-client-urls":["https://199.19.213.199:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:55:50.804596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:55:50.834597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=(7882989583592029417)"} {"level":"info","ts":"2026-03-17T21:55:50.834782Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6964c025ad43a005","local-member-id":"6d660142aa1b7ce9","added-peer-id":"6d660142aa1b7ce9","added-peer-peer-urls":["https://199.19.213.199:2380"]} {"level":"info","ts":"2026-03-17T21:55:51.381898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:55:51.382006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:55:51.382049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 received MsgPreVoteResp from 6d660142aa1b7ce9 at term 1"} {"level":"info","ts":"2026-03-17T21:55:51.382072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:55:51.382092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 received MsgVoteResp from 6d660142aa1b7ce9 at term 2"} {"level":"info","ts":"2026-03-17T21:55:51.382139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became leader at term 2"} {"level":"info","ts":"2026-03-17T21:55:51.382154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d660142aa1b7ce9 elected leader 6d660142aa1b7ce9 at term 2"} {"level":"info","ts":"2026-03-17T21:55:51.384982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:51.385974Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d660142aa1b7ce9","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.199:2379]}","request-path":"/0/members/6d660142aa1b7ce9/attributes","cluster-id":"6964c025ad43a005","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:55:51.386227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:51.386288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:51.388675Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:55:51.388728Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:55:51.390703Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:55:51.390765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.199:2379"} {"level":"info","ts":"2026-03-17T21:55:51.406859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6964c025ad43a005","local-member-id":"6d660142aa1b7ce9","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:51.406975Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:51.407015Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-17T21:56:01.562687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.612434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-17T21:56:01.562814Z","caller":"traceutil/trace.go:171","msg":"trace[130793474] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"221.84714ms","start":"2026-03-17T21:56:01.340947Z","end":"2026-03-17T21:56:01.562794Z","steps":["trace[130793474] 'range keys from in-memory index tree' (duration: 221.522612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.562838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.256078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-03-17T21:56:01.562948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.492435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-17T21:56:01.562921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.047577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-17T21:56:01.562958Z","caller":"traceutil/trace.go:171","msg":"trace[312284834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:339; }","duration":"121.424542ms","start":"2026-03-17T21:56:01.44151Z","end":"2026-03-17T21:56:01.562935Z","steps":["trace[312284834] 'range keys from in-memory index tree' (duration: 121.109286ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:01.562986Z","caller":"traceutil/trace.go:171","msg":"trace[1221955330] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"471.567037ms","start":"2026-03-17T21:56:01.091409Z","end":"2026-03-17T21:56:01.562976Z","steps":["trace[1221955330] 'range keys from in-memory index tree' (duration: 471.410823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.563013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.768636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-03-17T21:56:01.563025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.949198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-03-17T21:56:01.563022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.091384Z","time spent":"471.630488ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-03-17T21:56:01.563111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.905554ms","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-03-17T21:56:01.563116Z","caller":"traceutil/trace.go:171","msg":"trace[981082175] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:339; }","duration":"256.055301ms","start":"2026-03-17T21:56:01.307044Z","end":"2026-03-17T21:56:01.563099Z","steps":["trace[981082175] 'range keys from in-memory index tree' (duration: 255.895828ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:01.56301Z","caller":"traceutil/trace.go:171","msg":"trace[2012123461] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"171.17698ms","start":"2026-03-17T21:56:01.391814Z","end":"2026-03-17T21:56:01.562991Z","steps":["trace[2012123461] 'range keys from in-memory index tree' (duration: 170.937505ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:01.563149Z","caller":"traceutil/trace.go:171","msg":"trace[1309099121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"390.941065ms","start":"2026-03-17T21:56:01.172198Z","end":"2026-03-17T21:56:01.563139Z","steps":["trace[1309099121] 'range keys from in-memory index tree' (duration: 390.845163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.563177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.172158Z","time spent":"391.012097ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T21:56:01.562956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.013862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:56:01.563222Z","caller":"traceutil/trace.go:171","msg":"trace[600019566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:339; }","duration":"368.302429ms","start":"2026-03-17T21:56:01.194908Z","end":"2026-03-17T21:56:01.563211Z","steps":["trace[600019566] 'range keys from in-memory index tree' (duration: 367.939641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.563231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.821987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-03-17T21:56:01.563228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.771231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-17T21:56:01.563268Z","caller":"traceutil/trace.go:171","msg":"trace[1940035817] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:339; }","duration":"321.91579ms","start":"2026-03-17T21:56:01.241339Z","end":"2026-03-17T21:56:01.563255Z","steps":["trace[1940035817] 'range keys from in-memory index tree' (duration: 321.701835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.563306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.24132Z","time spent":"321.97843ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-03-17T21:56:01.563324Z","caller":"traceutil/trace.go:171","msg":"trace[1663564697] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:339; }","duration":"371.901044ms","start":"2026-03-17T21:56:01.191383Z","end":"2026-03-17T21:56:01.563284Z","steps":["trace[1663564697] 'range keys from in-memory index tree' (duration: 371.672339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.563366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.191361Z","time spent":"371.993686ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-17T21:56:01.563258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.194895Z","time spent":"368.355299ms","remote":"127.0.0.1:43454","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-03-17T21:56:01.563067Z","caller":"traceutil/trace.go:171","msg":"trace[1248847224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:339; }","duration":"421.861948ms","start":"2026-03-17T21:56:01.141189Z","end":"2026-03-17T21:56:01.563051Z","steps":["trace[1248847224] 'range keys from in-memory index tree' (duration: 421.687674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:01.56347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:01.141169Z","time spent":"422.292917ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-17T21:56:01.563381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.20641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-17T21:56:01.565933Z","caller":"traceutil/trace.go:171","msg":"trace[659930830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"274.909388ms","start":"2026-03-17T21:56:01.290972Z","end":"2026-03-17T21:56:01.565882Z","steps":["trace[659930830] 'range keys from in-memory index tree' (duration: 271.975356ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:06.739419Z","caller":"traceutil/trace.go:171","msg":"trace[1659036356] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"409.775932ms","start":"2026-03-17T21:56:06.329616Z","end":"2026-03-17T21:56:06.739392Z","steps":["trace[1659036356] 'process raft request' (duration: 409.576328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:06.74014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:06.329595Z","time spent":"409.899144ms","remote":"127.0.0.1:43626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:56:08.002336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.26311791s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:56:08.002742Z","caller":"traceutil/trace.go:171","msg":"trace[1509945471] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:419; }","duration":"1.274135042s","start":"2026-03-17T21:56:06.728582Z","end":"2026-03-17T21:56:08.002717Z","steps":["trace[1509945471] 'read index received' (duration: 10.636244ms)","trace[1509945471] 'applied index is now lower than readState.Index' (duration: 1.263497288s)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:08.002818Z","caller":"traceutil/trace.go:171","msg":"trace[1951686456] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.331213337s","start":"2026-03-17T21:56:06.671573Z","end":"2026-03-17T21:56:08.002786Z","steps":["trace[1951686456] 'process raft request' (duration: 1.330991772s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.002998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:06.671545Z","time spent":"1.331364579s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:56:08.003294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.895377ms","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-03-17T21:56:08.003448Z","caller":"traceutil/trace.go:171","msg":"trace[1895574512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"374.11279ms","start":"2026-03-17T21:56:07.629299Z","end":"2026-03-17T21:56:08.003412Z","steps":["trace[1895574512] 'agreement among raft nodes before linearized reading' (duration: 373.739963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.00303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.993873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:56:08.003509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:07.629281Z","time spent":"374.216353ms","remote":"127.0.0.1:43718","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":"info","ts":"2026-03-17T21:56:08.00353Z","caller":"traceutil/trace.go:171","msg":"trace[1077128172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"809.534234ms","start":"2026-03-17T21:56:07.193973Z","end":"2026-03-17T21:56:08.003507Z","steps":["trace[1077128172] 'agreement among raft nodes before linearized reading' (duration: 808.982082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.003578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:07.193958Z","time spent":"809.606996ms","remote":"127.0.0.1:43440","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-03-17T21:56:08.003028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.711093ms","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-03-17T21:56:08.003649Z","caller":"traceutil/trace.go:171","msg":"trace[242641493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"237.370007ms","start":"2026-03-17T21:56:07.766255Z","end":"2026-03-17T21:56:08.003625Z","steps":["trace[242641493] 'agreement among raft nodes before linearized reading' (duration: 236.704452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.003078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274457289s","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-03-17T21:56:08.003818Z","caller":"traceutil/trace.go:171","msg":"trace[92115164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"1.275256676s","start":"2026-03-17T21:56:06.728534Z","end":"2026-03-17T21:56:08.003791Z","steps":["trace[92115164] 'agreement among raft nodes before linearized reading' (duration: 1.274370527s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.003873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:06.72852Z","time spent":"1.275336698s","remote":"127.0.0.1:43718","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-03-17T21:56:08.003119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.461483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-17T21:56:08.003925Z","caller":"traceutil/trace.go:171","msg":"trace[1616444992] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:407; }","duration":"563.302351ms","start":"2026-03-17T21:56:07.440608Z","end":"2026-03-17T21:56:08.003911Z","steps":["trace[1616444992] 'agreement among raft nodes before linearized reading' (duration: 562.460223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.003966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:07.440589Z","time spent":"563.364292ms","remote":"127.0.0.1:43626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-17T21:56:08.392481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.996441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:56:08.392615Z","caller":"traceutil/trace.go:171","msg":"trace[1339964976] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"382.541609ms","start":"2026-03-17T21:56:08.010057Z","end":"2026-03-17T21:56:08.392599Z","steps":["trace[1339964976] 'process raft request' (duration: 180.20392ms)","trace[1339964976] 'compare' (duration: 201.539281ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:56:08.392667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:08.010042Z","time spent":"382.60316ms","remote":"127.0.0.1:43718","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-03-17T21:56:08.392852Z","caller":"traceutil/trace.go:171","msg":"trace[1972222174] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"382.549089ms","start":"2026-03-17T21:56:08.010294Z","end":"2026-03-17T21:56:08.392844Z","steps":["trace[1972222174] 'process raft request' (duration: 382.423006ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:08.39284Z","caller":"traceutil/trace.go:171","msg":"trace[1098057322] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:421; }","duration":"196.658708ms","start":"2026-03-17T21:56:08.19616Z","end":"2026-03-17T21:56:08.392818Z","steps":["trace[1098057322] 'read index received' (duration: 43.189791ms)","trace[1098057322] 'applied index is now lower than readState.Index' (duration: 153.467857ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:08.392879Z","caller":"traceutil/trace.go:171","msg":"trace[1698462871] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"382.068569ms","start":"2026-03-17T21:56:08.010789Z","end":"2026-03-17T21:56:08.392858Z","steps":["trace[1698462871] 'process raft request' (duration: 381.991128ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:08.392925Z","caller":"traceutil/trace.go:171","msg":"trace[1685290468] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"382.800145ms","start":"2026-03-17T21:56:08.010118Z","end":"2026-03-17T21:56:08.392919Z","steps":["trace[1685290468] 'process raft request' (duration: 382.448497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.392956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.801352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:56:08.392964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:08.010099Z","time spent":"382.848815ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:56:08.393001Z","caller":"traceutil/trace.go:171","msg":"trace[668002231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"196.899294ms","start":"2026-03-17T21:56:08.196091Z","end":"2026-03-17T21:56:08.392991Z","steps":["trace[668002231] 'agreement among raft nodes before linearized reading' (duration: 196.804352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:08.393009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:08.010778Z","time spent":"382.147231ms","remote":"127.0.0.1:43718","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-03-17T21:56:08.392898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:08.010273Z","time spent":"382.604311ms","remote":"127.0.0.1:43718","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-03-17T21:56:22.770218Z","caller":"traceutil/trace.go:171","msg":"trace[212720749] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"290.814156ms","start":"2026-03-17T21:56:22.479377Z","end":"2026-03-17T21:56:22.770191Z","steps":["trace[212720749] 'read index received' (duration: 290.617271ms)","trace[212720749] 'applied index is now lower than readState.Index' (duration: 195.785µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:22.770321Z","caller":"traceutil/trace.go:171","msg":"trace[40374963] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"291.45012ms","start":"2026-03-17T21:56:22.47883Z","end":"2026-03-17T21:56:22.77028Z","steps":["trace[40374963] 'process raft request' (duration: 291.161933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:22.770444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.033101ms","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-03-17T21:56:22.770494Z","caller":"traceutil/trace.go:171","msg":"trace[948287437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"291.119672ms","start":"2026-03-17T21:56:22.47936Z","end":"2026-03-17T21:56:22.77048Z","steps":["trace[948287437] 'agreement among raft nodes before linearized reading' (duration: 290.985099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:23.262062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.037915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:56:23.262222Z","caller":"traceutil/trace.go:171","msg":"trace[1465762240] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"780.636054ms","start":"2026-03-17T21:56:22.481534Z","end":"2026-03-17T21:56:23.26217Z","steps":["trace[1465762240] 'process raft request' (duration: 491.379773ms)","trace[1465762240] 'compare' (duration: 288.894132ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:23.262276Z","caller":"traceutil/trace.go:171","msg":"trace[665994330] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"485.965134ms","start":"2026-03-17T21:56:22.776292Z","end":"2026-03-17T21:56:23.262257Z","steps":["trace[665994330] 'process raft request' (duration: 485.903983ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:23.262283Z","caller":"traceutil/trace.go:171","msg":"trace[340145517] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"491.938917ms","start":"2026-03-17T21:56:22.770308Z","end":"2026-03-17T21:56:23.262247Z","steps":["trace[340145517] 'read index received' (duration: 202.747477ms)","trace[340145517] 'applied index is now lower than readState.Index' (duration: 289.18847ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:56:23.262354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:22.77626Z","time spent":"486.060347ms","remote":"127.0.0.1:43718","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-03-17T21:56:23.26245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:22.481518Z","time spent":"780.794578ms","remote":"127.0.0.1:43718","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-03-17T21:56:23.262507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.054078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"warn","ts":"2026-03-17T21:56:23.262489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.904786ms","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-03-17T21:56:23.262547Z","caller":"traceutil/trace.go:171","msg":"trace[550574841] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:509; }","duration":"420.15058ms","start":"2026-03-17T21:56:22.842385Z","end":"2026-03-17T21:56:23.262535Z","steps":["trace[550574841] 'agreement among raft nodes before linearized reading' (duration: 420.034798ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:23.26258Z","caller":"traceutil/trace.go:171","msg":"trace[1570764586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"782.995418ms","start":"2026-03-17T21:56:22.479555Z","end":"2026-03-17T21:56:23.262551Z","steps":["trace[1570764586] 'agreement among raft nodes before linearized reading' (duration: 782.832554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:23.262586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:22.842362Z","time spent":"420.215832ms","remote":"127.0.0.1:43626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-17T21:56:23.262616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:22.479471Z","time spent":"783.136171ms","remote":"127.0.0.1:43718","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-03-17T21:56:23.792847Z","caller":"traceutil/trace.go:171","msg":"trace[654628205] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"117.179075ms","start":"2026-03-17T21:56:23.675642Z","end":"2026-03-17T21:56:23.792821Z","steps":["trace[654628205] 'process raft request' (duration: 116.991481ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:25.471545Z","caller":"traceutil/trace.go:171","msg":"trace[346825403] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"174.151451ms","start":"2026-03-17T21:56:25.297367Z","end":"2026-03-17T21:56:25.471518Z","steps":["trace[346825403] 'read index received' (duration: 11.124717ms)","trace[346825403] 'applied index is now lower than readState.Index' (duration: 163.025524ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:25.471635Z","caller":"traceutil/trace.go:171","msg":"trace[1994727364] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"195.602129ms","start":"2026-03-17T21:56:25.276006Z","end":"2026-03-17T21:56:25.471609Z","steps":["trace[1994727364] 'process raft request' (duration: 189.066364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:25.471823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.417317ms","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-03-17T21:56:25.471911Z","caller":"traceutil/trace.go:171","msg":"trace[2087132820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"174.55746ms","start":"2026-03-17T21:56:25.297334Z","end":"2026-03-17T21:56:25.471891Z","steps":["trace[2087132820] 'agreement among raft nodes before linearized reading' (duration: 174.286034ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:28.60291Z","caller":"traceutil/trace.go:171","msg":"trace[1771493276] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"152.787647ms","start":"2026-03-17T21:56:28.450094Z","end":"2026-03-17T21:56:28.602882Z","steps":["trace[1771493276] 'process raft request' (duration: 135.611845ms)","trace[1771493276] 'compare' (duration: 17.037379ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:29.071095Z","caller":"traceutil/trace.go:171","msg":"trace[745212142] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"221.232578ms","start":"2026-03-17T21:56:28.849832Z","end":"2026-03-17T21:56:29.071064Z","steps":["trace[745212142] 'process raft request' (duration: 221.076855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.249552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.936192ms","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-03-17T21:56:30.249642Z","caller":"traceutil/trace.go:171","msg":"trace[1041114803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"929.048314ms","start":"2026-03-17T21:56:29.320559Z","end":"2026-03-17T21:56:30.249607Z","steps":["trace[1041114803] 'range keys from in-memory index tree' (duration: 928.830339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.249677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:29.320541Z","time spent":"929.124456ms","remote":"127.0.0.1:43718","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-03-17T21:56:30.249678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054158075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:56:30.249748Z","caller":"traceutil/trace.go:171","msg":"trace[804024275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"1.054302798s","start":"2026-03-17T21:56:29.195428Z","end":"2026-03-17T21:56:30.249731Z","steps":["trace[804024275] 'range keys from in-memory index tree' (duration: 1.053961101s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.2498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:29.195407Z","time spent":"1.05438448s","remote":"127.0.0.1:43440","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-03-17T21:56:30.24982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.901585ms","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-03-17T21:56:30.249885Z","caller":"traceutil/trace.go:171","msg":"trace[1846087818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"765.010578ms","start":"2026-03-17T21:56:29.484858Z","end":"2026-03-17T21:56:30.249868Z","steps":["trace[1846087818] 'range keys from in-memory index tree' (duration: 764.748021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.249909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.799059ms","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-03-17T21:56:30.249926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:29.484834Z","time spent":"765.083689ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-17T21:56:30.24996Z","caller":"traceutil/trace.go:171","msg":"trace[98109102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"758.881591ms","start":"2026-03-17T21:56:29.491065Z","end":"2026-03-17T21:56:30.249946Z","steps":["trace[98109102] 'range keys from in-memory index tree' (duration: 758.671766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.249996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:29.49105Z","time spent":"758.939222ms","remote":"127.0.0.1:43718","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-03-17T21:56:30.441543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.437195ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-17T21:56:30.441863Z","caller":"traceutil/trace.go:171","msg":"trace[829343200] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"189.361619ms","start":"2026-03-17T21:56:30.252466Z","end":"2026-03-17T21:56:30.441827Z","steps":["trace[829343200] 'read index received' (duration: 55.609697ms)","trace[829343200] 'applied index is now lower than readState.Index' (duration: 133.748772ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:30.442007Z","caller":"traceutil/trace.go:171","msg":"trace[1351787245] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"187.59049ms","start":"2026-03-17T21:56:30.254399Z","end":"2026-03-17T21:56:30.44199Z","steps":["trace[1351787245] 'process raft request' (duration: 187.350124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:30.442101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.607056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:56:30.442194Z","caller":"traceutil/trace.go:171","msg":"trace[1721460772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"189.743998ms","start":"2026-03-17T21:56:30.252425Z","end":"2026-03-17T21:56:30.442169Z","steps":["trace[1721460772] 'agreement among raft nodes before linearized reading' (duration: 189.509962ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:30.442366Z","caller":"traceutil/trace.go:171","msg":"trace[1419514663] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"187.858526ms","start":"2026-03-17T21:56:30.254444Z","end":"2026-03-17T21:56:30.442303Z","steps":["trace[1419514663] 'process raft request' (duration: 187.481197ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:30.442384Z","caller":"traceutil/trace.go:171","msg":"trace[911497120] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"187.209002ms","start":"2026-03-17T21:56:30.255142Z","end":"2026-03-17T21:56:30.442351Z","steps":["trace[911497120] 'process raft request' (duration: 187.060429ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:34.554234Z","caller":"traceutil/trace.go:171","msg":"trace[454827760] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"129.05431ms","start":"2026-03-17T21:56:34.42515Z","end":"2026-03-17T21:56:34.554205Z","steps":["trace[454827760] 'process raft request' (duration: 128.882186ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:34.59321Z","caller":"traceutil/trace.go:171","msg":"trace[1609813407] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"156.325495ms","start":"2026-03-17T21:56:34.436814Z","end":"2026-03-17T21:56:34.59314Z","steps":["trace[1609813407] 'read index received' (duration: 117.214926ms)","trace[1609813407] 'applied index is now lower than readState.Index' (duration: 39.109739ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:34.593252Z","caller":"traceutil/trace.go:171","msg":"trace[132101150] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"157.980702ms","start":"2026-03-17T21:56:34.435254Z","end":"2026-03-17T21:56:34.593235Z","steps":["trace[132101150] 'process raft request' (duration: 157.766068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:34.593369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.53701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-17T21:56:34.593409Z","caller":"traceutil/trace.go:171","msg":"trace[730217617] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:588; }","duration":"156.628042ms","start":"2026-03-17T21:56:34.436768Z","end":"2026-03-17T21:56:34.593396Z","steps":["trace[730217617] 'agreement among raft nodes before linearized reading' (duration: 156.5263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:34.593562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.428973ms","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-03-17T21:56:34.593605Z","caller":"traceutil/trace.go:171","msg":"trace[249434939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"136.534346ms","start":"2026-03-17T21:56:34.45706Z","end":"2026-03-17T21:56:34.593595Z","steps":["trace[249434939] 'agreement among raft nodes before linearized reading' (duration: 136.342431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:34.593657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.336609ms","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-03-17T21:56:34.593735Z","caller":"traceutil/trace.go:171","msg":"trace[1531114922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"130.471651ms","start":"2026-03-17T21:56:34.463244Z","end":"2026-03-17T21:56:34.593716Z","steps":["trace[1531114922] 'agreement among raft nodes before linearized reading' (duration: 130.320687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:34.593891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.728207ms","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-03-17T21:56:34.593958Z","caller":"traceutil/trace.go:171","msg":"trace[317329235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"130.818319ms","start":"2026-03-17T21:56:34.463125Z","end":"2026-03-17T21:56:34.593944Z","steps":["trace[317329235] 'agreement among raft nodes before linearized reading' (duration: 130.701886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:45.306325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.388579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:56:45.306392Z","caller":"traceutil/trace.go:171","msg":"trace[839815110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"112.510522ms","start":"2026-03-17T21:56:45.193868Z","end":"2026-03-17T21:56:45.306378Z","steps":["trace[839815110] 'range keys from in-memory index tree' (duration: 112.295486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.176134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144911867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T21:56:51.318554Z","caller":"traceutil/trace.go:171","msg":"trace[657966581] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"644.552819ms","start":"2026-03-17T21:56:50.673979Z","end":"2026-03-17T21:56:51.318531Z","steps":["trace[657966581] 'process raft request' (duration: 644.367865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.318783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:50.67396Z","time spent":"644.718302ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:56:51.532731Z","caller":"traceutil/trace.go:171","msg":"trace[1795352454] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"856.868639ms","start":"2026-03-17T21:56:50.675827Z","end":"2026-03-17T21:56:51.532696Z","steps":["trace[1795352454] 'read index received' (duration: 642.520254ms)","trace[1795352454] 'applied index is now lower than readState.Index' (duration: 214.347615ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:51.532822Z","caller":"traceutil/trace.go:171","msg":"trace[1867473863] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"856.761688ms","start":"2026-03-17T21:56:50.676052Z","end":"2026-03-17T21:56:51.532813Z","steps":["trace[1867473863] 'process raft request' (duration: 855.535321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.532907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:50.676006Z","time spent":"856.834129ms","remote":"127.0.0.1:43718","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-03-17T21:56:51.53304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.837349ms","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-03-17T21:56:51.533113Z","caller":"traceutil/trace.go:171","msg":"trace[1495819328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"363.956871ms","start":"2026-03-17T21:56:51.169137Z","end":"2026-03-17T21:56:51.533094Z","steps":["trace[1495819328] 'agreement among raft nodes before linearized reading' (duration: 363.753027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.533154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:51.169108Z","time spent":"364.037423ms","remote":"127.0.0.1:43718","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-03-17T21:56:51.533207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.377351ms","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-03-17T21:56:51.533236Z","caller":"traceutil/trace.go:171","msg":"trace[814110150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"857.438312ms","start":"2026-03-17T21:56:50.675787Z","end":"2026-03-17T21:56:51.533226Z","steps":["trace[814110150] 'agreement among raft nodes before linearized reading' (duration: 857.33837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.533256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:50.675759Z","time spent":"857.492353ms","remote":"127.0.0.1:43718","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":"info","ts":"2026-03-17T21:56:51.79365Z","caller":"traceutil/trace.go:171","msg":"trace[373023168] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"256.267916ms","start":"2026-03-17T21:56:51.537343Z","end":"2026-03-17T21:56:51.79361Z","steps":["trace[373023168] 'process raft request' (duration: 208.058505ms)","trace[373023168] 'compare' (duration: 48.111509ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:51.841004Z","caller":"traceutil/trace.go:171","msg":"trace[1587527578] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"303.528208ms","start":"2026-03-17T21:56:51.537451Z","end":"2026-03-17T21:56:51.840979Z","steps":["trace[1587527578] 'process raft request' (duration: 303.385225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:51.841185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:51.537432Z","time spent":"303.65217ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:56:52.342607Z","caller":"traceutil/trace.go:171","msg":"trace[1255380649] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"137.992558ms","start":"2026-03-17T21:56:52.204592Z","end":"2026-03-17T21:56:52.342584Z","steps":["trace[1255380649] 'process raft request' (duration: 137.860715ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:15.57631Z","caller":"traceutil/trace.go:171","msg":"trace[73759126] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"149.308843ms","start":"2026-03-17T21:57:15.426972Z","end":"2026-03-17T21:57:15.57628Z","steps":["trace[73759126] 'process raft request' (duration: 149.126549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:16.183367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.478827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:16.18358Z","caller":"traceutil/trace.go:171","msg":"trace[1130735078] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:786; }","duration":"445.743593ms","start":"2026-03-17T21:57:15.737804Z","end":"2026-03-17T21:57:16.183548Z","steps":["trace[1130735078] 'count revisions from in-memory index tree' (duration: 445.371005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:16.183705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:15.737792Z","time spent":"445.888506ms","remote":"127.0.0.1:43588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:57:16.183401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.701483ms","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-03-17T21:57:16.183899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.798463ms","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-03-17T21:57:16.18391Z","caller":"traceutil/trace.go:171","msg":"trace[966056423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"249.245605ms","start":"2026-03-17T21:57:15.934622Z","end":"2026-03-17T21:57:16.183868Z","steps":["trace[966056423] 'range keys from in-memory index tree' (duration: 248.472329ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:16.183975Z","caller":"traceutil/trace.go:171","msg":"trace[1794962466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"278.931276ms","start":"2026-03-17T21:57:15.905028Z","end":"2026-03-17T21:57:16.183959Z","steps":["trace[1794962466] 'range keys from in-memory index tree' (duration: 278.591789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:16.183967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.013791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:57:16.184105Z","caller":"traceutil/trace.go:171","msg":"trace[942124620] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:786; }","duration":"499.175294ms","start":"2026-03-17T21:57:15.684907Z","end":"2026-03-17T21:57:16.184083Z","steps":["trace[942124620] 'count revisions from in-memory index tree' (duration: 498.934708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:16.184208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:15.684897Z","time spent":"499.289777ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:57:16.184468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.856743ms","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-03-17T21:57:16.184585Z","caller":"traceutil/trace.go:171","msg":"trace[673315240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"502.029377ms","start":"2026-03-17T21:57:15.682531Z","end":"2026-03-17T21:57:16.18456Z","steps":["trace[673315240] 'range keys from in-memory index tree' (duration: 500.945423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:16.184996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:15.682509Z","time spent":"502.117528ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-17T21:57:16.376992Z","caller":"traceutil/trace.go:171","msg":"trace[938351267] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"188.121744ms","start":"2026-03-17T21:57:16.188851Z","end":"2026-03-17T21:57:16.376973Z","steps":["trace[938351267] 'process raft request' (duration: 187.988571ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:16.377228Z","caller":"traceutil/trace.go:171","msg":"trace[1587785367] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:821; }","duration":"184.25861ms","start":"2026-03-17T21:57:16.192908Z","end":"2026-03-17T21:57:16.377167Z","steps":["trace[1587785367] 'read index received' (duration: 61.405096ms)","trace[1587785367] 'applied index is now lower than readState.Index' (duration: 122.851974ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:16.377464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.537136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:16.377512Z","caller":"traceutil/trace.go:171","msg":"trace[617639908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"184.611327ms","start":"2026-03-17T21:57:16.192889Z","end":"2026-03-17T21:57:16.3775Z","steps":["trace[617639908] 'agreement among raft nodes before linearized reading' (duration: 184.437813ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:16.377507Z","caller":"traceutil/trace.go:171","msg":"trace[979790593] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"184.698779ms","start":"2026-03-17T21:57:16.192771Z","end":"2026-03-17T21:57:16.37747Z","steps":["trace[979790593] 'process raft request' (duration: 184.168868ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:16.677334Z","caller":"traceutil/trace.go:171","msg":"trace[1873591338] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"141.585484ms","start":"2026-03-17T21:57:16.53573Z","end":"2026-03-17T21:57:16.677315Z","steps":["trace[1873591338] 'process raft request' (duration: 141.014301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:17.231315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.565603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:17.231449Z","caller":"traceutil/trace.go:171","msg":"trace[1394798959] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:790; }","duration":"270.716956ms","start":"2026-03-17T21:57:16.960667Z","end":"2026-03-17T21:57:17.231384Z","steps":["trace[1394798959] 'count revisions from in-memory index tree' (duration: 270.365319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:18.11161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.777995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:57:18.111764Z","caller":"traceutil/trace.go:171","msg":"trace[1319760975] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:791; }","duration":"312.264966ms","start":"2026-03-17T21:57:17.79947Z","end":"2026-03-17T21:57:18.111735Z","steps":["trace[1319760975] 'count revisions from in-memory index tree' (duration: 311.676483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:18.111823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:17.799449Z","time spent":"312.357488ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-03-17T21:57:18.817915Z","caller":"traceutil/trace.go:171","msg":"trace[1603101508] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"132.881323ms","start":"2026-03-17T21:57:18.685009Z","end":"2026-03-17T21:57:18.817891Z","steps":["trace[1603101508] 'process raft request' (duration: 132.724769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:19.950456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.231406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:19.950653Z","caller":"traceutil/trace.go:171","msg":"trace[1505859196] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"189.136028ms","start":"2026-03-17T21:57:19.761492Z","end":"2026-03-17T21:57:19.950628Z","steps":["trace[1505859196] 'process raft request' (duration: 57.611194ms)","trace[1505859196] 'compare' (duration: 131.052702ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:21.959749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294119874s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-17T21:57:21.959851Z","caller":"traceutil/trace.go:171","msg":"trace[677855886] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"1.764429104s","start":"2026-03-17T21:57:20.195404Z","end":"2026-03-17T21:57:21.959833Z","steps":["trace[677855886] 'read index received' (duration: 470.29368ms)","trace[677855886] 'applied index is now lower than readState.Index' (duration: 1.294133704s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:21.960119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764711511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:21.960197Z","caller":"traceutil/trace.go:171","msg":"trace[61453913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"1.764841444s","start":"2026-03-17T21:57:20.195341Z","end":"2026-03-17T21:57:21.960182Z","steps":["trace[61453913] 'agreement among raft nodes before linearized reading' (duration: 1.764716131s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.195306Z","time spent":"1.764927836s","remote":"127.0.0.1:43440","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-03-17T21:57:21.960278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672052009s","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-03-17T21:57:21.960361Z","caller":"traceutil/trace.go:171","msg":"trace[1844009546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.672184122s","start":"2026-03-17T21:57:20.288156Z","end":"2026-03-17T21:57:21.96034Z","steps":["trace[1844009546] 'agreement among raft nodes before linearized reading' (duration: 1.671955627s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.582306ms","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-03-17T21:57:21.960328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489407625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:57:21.96041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.288131Z","time spent":"1.672268504s","remote":"127.0.0.1:43718","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-03-17T21:57:21.960427Z","caller":"traceutil/trace.go:171","msg":"trace[932210911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"220.673979ms","start":"2026-03-17T21:57:21.739733Z","end":"2026-03-17T21:57:21.960407Z","steps":["trace[932210911] 'agreement among raft nodes before linearized reading' (duration: 220.498365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489604419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:57:21.960376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554331078s","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-03-17T21:57:21.960489Z","caller":"traceutil/trace.go:171","msg":"trace[1321005276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"1.554510502s","start":"2026-03-17T21:57:20.40597Z","end":"2026-03-17T21:57:21.96048Z","steps":["trace[1321005276] 'agreement among raft nodes before linearized reading' (duration: 1.554248786s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:21.960494Z","caller":"traceutil/trace.go:171","msg":"trace[1038782881] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:797; }","duration":"1.489697541s","start":"2026-03-17T21:57:20.470778Z","end":"2026-03-17T21:57:21.960476Z","steps":["trace[1038782881] 'agreement among raft nodes before linearized reading' (duration: 1.48964141s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.405947Z","time spent":"1.554567934s","remote":"127.0.0.1:43718","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-03-17T21:57:21.960532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.470744Z","time spent":"1.489778543s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-17T21:57:21.960448Z","caller":"traceutil/trace.go:171","msg":"trace[1141979485] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:797; }","duration":"1.489593149s","start":"2026-03-17T21:57:20.470834Z","end":"2026-03-17T21:57:21.960427Z","steps":["trace[1141979485] 'agreement among raft nodes before linearized reading' (duration: 1.489360124s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559167175s","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-03-17T21:57:21.960635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138109272s","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-03-17T21:57:21.960675Z","caller":"traceutil/trace.go:171","msg":"trace[1717925641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.559254247s","start":"2026-03-17T21:57:20.401411Z","end":"2026-03-17T21:57:21.960665Z","steps":["trace[1717925641] 'agreement among raft nodes before linearized reading' (duration: 1.559145885s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.470809Z","time spent":"1.489853395s","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-03-17T21:57:21.960694Z","caller":"traceutil/trace.go:171","msg":"trace[785341621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.138204894s","start":"2026-03-17T21:57:20.822468Z","end":"2026-03-17T21:57:21.960673Z","steps":["trace[785341621] 'agreement among raft nodes before linearized reading' (duration: 1.138085282s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:21.960704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.401378Z","time spent":"1.559318748s","remote":"127.0.0.1:43718","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-03-17T21:57:21.960734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:20.822437Z","time spent":"1.138285656s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-17T21:57:22.077057Z","caller":"traceutil/trace.go:171","msg":"trace[501914803] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"111.983145ms","start":"2026-03-17T21:57:21.96505Z","end":"2026-03-17T21:57:22.077033Z","steps":["trace[501914803] 'process raft request' (duration: 111.772879ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:22.077343Z","caller":"traceutil/trace.go:171","msg":"trace[360612769] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"111.966945ms","start":"2026-03-17T21:57:21.965349Z","end":"2026-03-17T21:57:22.077316Z","steps":["trace[360612769] 'process raft request' (duration: 111.641838ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:22.077568Z","caller":"traceutil/trace.go:171","msg":"trace[1305671070] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"111.75469ms","start":"2026-03-17T21:57:21.965796Z","end":"2026-03-17T21:57:22.07755Z","steps":["trace[1305671070] 'process raft request' (duration: 111.482404ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:22.077541Z","caller":"traceutil/trace.go:171","msg":"trace[94286432] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:833; }","duration":"108.903348ms","start":"2026-03-17T21:57:21.968619Z","end":"2026-03-17T21:57:22.077522Z","steps":["trace[94286432] 'read index received' (duration: 23.036444ms)","trace[94286432] 'applied index is now lower than readState.Index' (duration: 85.865944ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:22.077605Z","caller":"traceutil/trace.go:171","msg":"trace[1103494560] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"111.521785ms","start":"2026-03-17T21:57:21.966061Z","end":"2026-03-17T21:57:22.077583Z","steps":["trace[1103494560] 'process raft request' (duration: 111.421343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:22.077659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.00307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:22.077779Z","caller":"traceutil/trace.go:171","msg":"trace[1986312916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"109.180234ms","start":"2026-03-17T21:57:21.968585Z","end":"2026-03-17T21:57:22.077765Z","steps":["trace[1986312916] 'agreement among raft nodes before linearized reading' (duration: 109.01481ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:30.256894Z","caller":"traceutil/trace.go:171","msg":"trace[2015492386] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"101.841943ms","start":"2026-03-17T21:57:30.155015Z","end":"2026-03-17T21:57:30.256857Z","steps":["trace[2015492386] 'process raft request' (duration: 101.391243ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:30.448882Z","caller":"traceutil/trace.go:171","msg":"trace[1096624253] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:859; }","duration":"177.859679ms","start":"2026-03-17T21:57:30.270991Z","end":"2026-03-17T21:57:30.44885Z","steps":["trace[1096624253] 'read index received' (duration: 39.23602ms)","trace[1096624253] 'applied index is now lower than readState.Index' (duration: 138.621579ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:30.44903Z","caller":"traceutil/trace.go:171","msg":"trace[948624115] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"188.020282ms","start":"2026-03-17T21:57:30.260967Z","end":"2026-03-17T21:57:30.448987Z","steps":["trace[948624115] 'process raft request' (duration: 101.120707ms)","trace[948624115] 'compare' (duration: 86.63615ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:30.449254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.157855ms","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-03-17T21:57:30.449304Z","caller":"traceutil/trace.go:171","msg":"trace[1901001369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"178.35484ms","start":"2026-03-17T21:57:30.270937Z","end":"2026-03-17T21:57:30.449292Z","steps":["trace[1901001369] 'agreement among raft nodes before linearized reading' (duration: 178.088614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:30.449376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.947431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-17T21:57:30.449423Z","caller":"traceutil/trace.go:171","msg":"trace[1917932798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"178.018292ms","start":"2026-03-17T21:57:30.271388Z","end":"2026-03-17T21:57:30.449406Z","steps":["trace[1917932798] 'agreement among raft nodes before linearized reading' (duration: 177.856359ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:34.567277Z","caller":"traceutil/trace.go:171","msg":"trace[108371101] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"101.870473ms","start":"2026-03-17T21:57:34.465384Z","end":"2026-03-17T21:57:34.567254Z","steps":["trace[108371101] 'process raft request' (duration: 101.690159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:36.693528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:57:37.194682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:57:37.695652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:57:37.81301Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.718002747s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:57:37.81327Z","caller":"traceutil/trace.go:171","msg":"trace[597958342] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"1.619847694s","start":"2026-03-17T21:57:36.193406Z","end":"2026-03-17T21:57:37.813254Z","steps":["trace[597958342] 'read index received' (duration: 1.619693481s)","trace[597958342] 'applied index is now lower than readState.Index' (duration: 153.583µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:37.813507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620025818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:37.814503Z","caller":"traceutil/trace.go:171","msg":"trace[1270080686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"1.621134042s","start":"2026-03-17T21:57:36.193357Z","end":"2026-03-17T21:57:37.814491Z","steps":["trace[1270080686] 'agreement among raft nodes before linearized reading' (duration: 1.620023728s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.814539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.193339Z","time spent":"1.621191473s","remote":"127.0.0.1:43440","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-03-17T21:57:37.813651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242105162s","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-03-17T21:57:37.814606Z","caller":"traceutil/trace.go:171","msg":"trace[644736182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"1.243103764s","start":"2026-03-17T21:57:36.571477Z","end":"2026-03-17T21:57:37.814581Z","steps":["trace[644736182] 'agreement among raft nodes before linearized reading' (duration: 1.242074062s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.81469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.571459Z","time spent":"1.243200546s","remote":"127.0.0.1:43718","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-03-17T21:57:37.813728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237360429s","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-03-17T21:57:37.814773Z","caller":"traceutil/trace.go:171","msg":"trace[369446163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.238435962s","start":"2026-03-17T21:57:36.57633Z","end":"2026-03-17T21:57:37.814766Z","steps":["trace[369446163] 'agreement among raft nodes before linearized reading' (duration: 1.237285917s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.814796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.576313Z","time spent":"1.238476123s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-17T21:57:37.813674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236815947s","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-03-17T21:57:37.814846Z","caller":"traceutil/trace.go:171","msg":"trace[996603273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.238009403s","start":"2026-03-17T21:57:36.576832Z","end":"2026-03-17T21:57:37.814841Z","steps":["trace[996603273] 'agreement among raft nodes before linearized reading' (duration: 1.236678404s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.814862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.576827Z","time spent":"1.238030573s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-17T21:57:37.813565Z","caller":"traceutil/trace.go:171","msg":"trace[1436241060] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"1.71858992s","start":"2026-03-17T21:57:36.094912Z","end":"2026-03-17T21:57:37.813502Z","steps":["trace[1436241060] 'process raft request' (duration: 1.718231572s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.814946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.09489Z","time spent":"1.720017391s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:57:37.813575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481729247s","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-03-17T21:57:37.815Z","caller":"traceutil/trace.go:171","msg":"trace[7024593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"1.483163949s","start":"2026-03-17T21:57:36.331831Z","end":"2026-03-17T21:57:37.814995Z","steps":["trace[7024593] 'agreement among raft nodes before linearized reading' (duration: 1.481656235s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.815017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.331787Z","time spent":"1.48322579s","remote":"127.0.0.1:43718","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-03-17T21:57:37.813801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404502802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:57:37.815066Z","caller":"traceutil/trace.go:171","msg":"trace[2037492438] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:889; }","duration":"1.405837712s","start":"2026-03-17T21:57:36.409222Z","end":"2026-03-17T21:57:37.81506Z","steps":["trace[2037492438] 'agreement among raft nodes before linearized reading' (duration: 1.404417621s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:37.815084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:36.409201Z","time spent":"1.405877294s","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":106,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-17T21:57:50.22984Z","caller":"traceutil/trace.go:171","msg":"trace[1554957227] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"300.311675ms","start":"2026-03-17T21:57:49.929502Z","end":"2026-03-17T21:57:50.229814Z","steps":["trace[1554957227] 'process raft request' (duration: 300.062919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:50.230013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:49.929485Z","time spent":"300.443137ms","remote":"127.0.0.1:43718","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-03-17T21:57:50.230113Z","caller":"traceutil/trace.go:171","msg":"trace[1295798858] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"300.506889ms","start":"2026-03-17T21:57:49.929591Z","end":"2026-03-17T21:57:50.230098Z","steps":["trace[1295798858] 'process raft request' (duration: 300.168031ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:50.230114Z","caller":"traceutil/trace.go:171","msg":"trace[473482787] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"300.411806ms","start":"2026-03-17T21:57:49.929674Z","end":"2026-03-17T21:57:50.230086Z","steps":["trace[473482787] 'process raft request' (duration: 300.309174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:50.230165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:49.929571Z","time spent":"300.565469ms","remote":"127.0.0.1:43718","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-03-17T21:57:50.230272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:49.929616Z","time spent":"300.57101ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:57:52.460082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912432,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:57:52.961021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912432,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:57:52.998326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064952709s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:57:52.998768Z","caller":"traceutil/trace.go:171","msg":"trace[682696440] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"1.038947409s","start":"2026-03-17T21:57:51.959799Z","end":"2026-03-17T21:57:52.998746Z","steps":["trace[682696440] 'read index received' (duration: 1.038745444s)","trace[682696440] 'applied index is now lower than readState.Index' (duration: 201.185µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:52.998892Z","caller":"traceutil/trace.go:171","msg":"trace[1209621009] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.065585423s","start":"2026-03-17T21:57:51.933272Z","end":"2026-03-17T21:57:52.998857Z","steps":["trace[1209621009] 'process raft request' (duration: 1.065332958s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.999032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.933248Z","time spent":"1.065703005s","remote":"127.0.0.1:43718","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-03-17T21:57:52.999079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039269106s","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-03-17T21:57:52.999116Z","caller":"traceutil/trace.go:171","msg":"trace[1752809376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"1.039339158s","start":"2026-03-17T21:57:51.959764Z","end":"2026-03-17T21:57:52.999103Z","steps":["trace[1752809376] 'agreement among raft nodes before linearized reading' (duration: 1.039202265s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.999167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.959751Z","time spent":"1.039406869s","remote":"127.0.0.1:43718","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-03-17T21:57:52.999206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.222425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-17T21:57:52.999214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.596076ms","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-03-17T21:57:52.999275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.791716ms","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-03-17T21:57:52.999285Z","caller":"traceutil/trace.go:171","msg":"trace[1855191648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"765.695858ms","start":"2026-03-17T21:57:52.233572Z","end":"2026-03-17T21:57:52.999268Z","steps":["trace[1855191648] 'agreement among raft nodes before linearized reading' (duration: 765.522834ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:52.999322Z","caller":"traceutil/trace.go:171","msg":"trace[1347384193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"763.877558ms","start":"2026-03-17T21:57:52.23543Z","end":"2026-03-17T21:57:52.999307Z","steps":["trace[1347384193] 'agreement among raft nodes before linearized reading' (duration: 763.729115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.99936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:52.235406Z","time spent":"763.94825ms","remote":"127.0.0.1:43718","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-03-17T21:57:52.999325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:52.233558Z","time spent":"765.75872ms","remote":"127.0.0.1:43718","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":"info","ts":"2026-03-17T21:57:52.999272Z","caller":"traceutil/trace.go:171","msg":"trace[860816102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"764.315848ms","start":"2026-03-17T21:57:52.234939Z","end":"2026-03-17T21:57:52.999255Z","steps":["trace[860816102] 'agreement among raft nodes before linearized reading' (duration: 764.145094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.999542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:52.234928Z","time spent":"764.603404ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-17T21:57:52.999629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.288193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:52.99969Z","caller":"traceutil/trace.go:171","msg":"trace[1767043095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"804.957758ms","start":"2026-03-17T21:57:52.194721Z","end":"2026-03-17T21:57:52.999679Z","steps":["trace[1767043095] 'agreement among raft nodes before linearized reading' (duration: 804.292914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.99972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:52.194701Z","time spent":"805.011329ms","remote":"127.0.0.1:43454","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-03-17T21:57:53.186793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:53.18698Z","caller":"traceutil/trace.go:171","msg":"trace[1614004815] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"183.393621ms","start":"2026-03-17T21:57:53.003567Z","end":"2026-03-17T21:57:53.18696Z","steps":["trace[1614004815] 'process raft request' (duration: 76.096439ms)","trace[1614004815] 'compare' (duration: 106.949055ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:53.187213Z","caller":"traceutil/trace.go:171","msg":"trace[1467112705] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"182.037091ms","start":"2026-03-17T21:57:53.005158Z","end":"2026-03-17T21:57:53.187195Z","steps":["trace[1467112705] 'process raft request' (duration: 181.96899ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:53.18724Z","caller":"traceutil/trace.go:171","msg":"trace[1560328475] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"182.487161ms","start":"2026-03-17T21:57:53.00474Z","end":"2026-03-17T21:57:53.187227Z","steps":["trace[1560328475] 'process raft request' (duration: 182.225625ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:53.187069Z","caller":"traceutil/trace.go:171","msg":"trace[1583302269] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"182.371738ms","start":"2026-03-17T21:57:53.004677Z","end":"2026-03-17T21:57:53.187049Z","steps":["trace[1583302269] 'process raft request' (duration: 182.216845ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:55.387723Z","caller":"traceutil/trace.go:171","msg":"trace[523298514] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"184.581176ms","start":"2026-03-17T21:57:55.203124Z","end":"2026-03-17T21:57:55.387705Z","steps":["trace[523298514] 'process raft request' (duration: 184.548545ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:55.387791Z","caller":"traceutil/trace.go:171","msg":"trace[1889461939] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"187.081611ms","start":"2026-03-17T21:57:55.200668Z","end":"2026-03-17T21:57:55.38775Z","steps":["trace[1889461939] 'process raft request' (duration: 186.924618ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:55.388215Z","caller":"traceutil/trace.go:171","msg":"trace[27104808] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"187.687735ms","start":"2026-03-17T21:57:55.200517Z","end":"2026-03-17T21:57:55.388205Z","steps":["trace[27104808] 'process raft request' (duration: 186.978448ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:55.592506Z","caller":"traceutil/trace.go:171","msg":"trace[1096596940] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"140.965202ms","start":"2026-03-17T21:57:55.451513Z","end":"2026-03-17T21:57:55.592478Z","steps":["trace[1096596940] 'process raft request' (duration: 140.824628ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:59.369452Z","caller":"traceutil/trace.go:171","msg":"trace[294540079] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"111.759071ms","start":"2026-03-17T21:57:59.257672Z","end":"2026-03-17T21:57:59.369431Z","steps":["trace[294540079] 'process raft request' (duration: 111.640388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:01.337123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03900281s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:01.337393Z","caller":"traceutil/trace.go:171","msg":"trace[462581573] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.930620448s","start":"2026-03-17T21:57:59.406737Z","end":"2026-03-17T21:58:01.337357Z","steps":["trace[462581573] 'process raft request' (duration: 891.278191ms)","trace[462581573] 'compare' (duration: 1.038690793s)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:01.337723Z","caller":"traceutil/trace.go:171","msg":"trace[2113254811] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.929972434s","start":"2026-03-17T21:57:59.407728Z","end":"2026-03-17T21:58:01.3377Z","steps":["trace[2113254811] 'process raft request' (duration: 1.929481033s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:01.338133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:59.40772Z","time spent":"1.930325133s","remote":"127.0.0.1:43718","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-03-17T21:58:01.337738Z","caller":"traceutil/trace.go:171","msg":"trace[222888246] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.928833939s","start":"2026-03-17T21:57:59.408868Z","end":"2026-03-17T21:58:01.337702Z","steps":["trace[222888246] 'process raft request' (duration: 1.928475411s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:01.337936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:59.406726Z","time spent":"1.931115429s","remote":"127.0.0.1:43718","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-03-17T21:58:01.338389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:59.40886Z","time spent":"1.929452623s","remote":"127.0.0.1:43718","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-03-17T21:58:02.195042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00003679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-17T21:58:02.195178Z","caller":"traceutil/trace.go:171","msg":"trace[443230799] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000233065s","start":"2026-03-17T21:58:00.194918Z","end":"2026-03-17T21:58:02.195151Z","steps":["trace[443230799] 'agreement among raft nodes before linearized reading' (duration: 2.000071641s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.195247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:00.194892Z","time spent":"2.000340056s","remote":"127.0.0.1:43440","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-03-17T21:58:02.300454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.189810187s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-17T21:58:02.805491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468088107s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-17T21:58:02.806203Z","caller":"traceutil/trace.go:171","msg":"trace[1137534421] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1019; }","duration":"2.791553504s","start":"2026-03-17T21:58:00.014623Z","end":"2026-03-17T21:58:02.806177Z","steps":["trace[1137534421] 'read index received' (duration: 283.402783ms)","trace[1137534421] 'applied index is now lower than readState.Index' (duration: 2.508147591s)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:02.806251Z","caller":"traceutil/trace.go:171","msg":"trace[1160428442] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.695713178s","start":"2026-03-17T21:58:01.110518Z","end":"2026-03-17T21:58:02.806231Z","steps":["trace[1160428442] 'process raft request' (duration: 1.695533815s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.806374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:01.110493Z","time spent":"1.695806561s","remote":"127.0.0.1:43718","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-03-17T21:58:02.806386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.791753889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:02.806432Z","caller":"traceutil/trace.go:171","msg":"trace[872564031] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:978; }","duration":"2.79184437s","start":"2026-03-17T21:58:00.014573Z","end":"2026-03-17T21:58:02.806417Z","steps":["trace[872564031] 'agreement among raft nodes before linearized reading' (duration: 2.791738558s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.806475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:00.014531Z","time spent":"2.791930692s","remote":"127.0.0.1:43708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:58:02.806776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.964096702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:58:02.806826Z","caller":"traceutil/trace.go:171","msg":"trace[1509491883] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:978; }","duration":"1.964180174s","start":"2026-03-17T21:58:00.842632Z","end":"2026-03-17T21:58:02.806812Z","steps":["trace[1509491883] 'agreement among raft nodes before linearized reading' (duration: 1.964076062s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.80687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:00.842616Z","time spent":"1.964239365s","remote":"127.0.0.1:43488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:58:02.806877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.451324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:02.806933Z","caller":"traceutil/trace.go:171","msg":"trace[175980993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"787.545066ms","start":"2026-03-17T21:58:02.019368Z","end":"2026-03-17T21:58:02.806913Z","steps":["trace[175980993] 'agreement among raft nodes before linearized reading' (duration: 787.462824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.806929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.021855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-17T21:58:02.806973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:02.019345Z","time spent":"787.618658ms","remote":"127.0.0.1:43440","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-03-17T21:58:02.80697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433042379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-17T21:58:02.807Z","caller":"traceutil/trace.go:171","msg":"trace[1185804543] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:978; }","duration":"922.135768ms","start":"2026-03-17T21:58:01.884845Z","end":"2026-03-17T21:58:02.806981Z","steps":["trace[1185804543] 'agreement among raft nodes before linearized reading' (duration: 922.010395ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:02.807022Z","caller":"traceutil/trace.go:171","msg":"trace[831804727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.433132271s","start":"2026-03-17T21:58:01.373875Z","end":"2026-03-17T21:58:02.807007Z","steps":["trace[831804727] 'agreement among raft nodes before linearized reading' (duration: 1.432957737s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.807045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:01.884829Z","time spent":"922.206449ms","remote":"127.0.0.1:43716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:58:02.807065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:01.373856Z","time spent":"1.433197663s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:02.807101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509041855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:58:02.807149Z","caller":"traceutil/trace.go:171","msg":"trace[1582105240] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:978; }","duration":"1.509088406s","start":"2026-03-17T21:58:01.298044Z","end":"2026-03-17T21:58:02.807132Z","steps":["trace[1582105240] 'agreement among raft nodes before linearized reading' (duration: 1.509015974s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.807189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:01.297987Z","time spent":"1.509189569s","remote":"127.0.0.1:59172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:58:02.807189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.306713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:02.807235Z","caller":"traceutil/trace.go:171","msg":"trace[135642397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"611.381984ms","start":"2026-03-17T21:58:02.195839Z","end":"2026-03-17T21:58:02.807221Z","steps":["trace[135642397] 'agreement among raft nodes before linearized reading' (duration: 611.312672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.807244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744324334s","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-03-17T21:58:02.807259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.690295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-03-17T21:58:02.807276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:02.195821Z","time spent":"611.442036ms","remote":"127.0.0.1:43454","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-03-17T21:58:02.807289Z","caller":"traceutil/trace.go:171","msg":"trace[768686099] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:978; }","duration":"660.757637ms","start":"2026-03-17T21:58:02.146524Z","end":"2026-03-17T21:58:02.807281Z","steps":["trace[768686099] 'agreement among raft nodes before linearized reading' (duration: 660.647575ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:02.807289Z","caller":"traceutil/trace.go:171","msg":"trace[1210629535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.744402356s","start":"2026-03-17T21:58:01.062874Z","end":"2026-03-17T21:58:02.807276Z","steps":["trace[1210629535] 'agreement among raft nodes before linearized reading' (duration: 1.744283703s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:02.807311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:02.146502Z","time spent":"660.804378ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-17T21:58:02.807329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:01.062855Z","time spent":"1.744463887s","remote":"127.0.0.1:43718","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-03-17T21:58:02.982405Z","caller":"traceutil/trace.go:171","msg":"trace[537936506] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"169.546787ms","start":"2026-03-17T21:58:02.812832Z","end":"2026-03-17T21:58:02.982379Z","steps":["trace[537936506] 'process raft request' (duration: 169.502936ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:02.982579Z","caller":"traceutil/trace.go:171","msg":"trace[417401422] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"171.306306ms","start":"2026-03-17T21:58:02.811246Z","end":"2026-03-17T21:58:02.982553Z","steps":["trace[417401422] 'process raft request' (duration: 119.998551ms)","trace[417401422] 'compare' (duration: 50.768083ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:02.982774Z","caller":"traceutil/trace.go:171","msg":"trace[1294400398] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"170.185222ms","start":"2026-03-17T21:58:02.812576Z","end":"2026-03-17T21:58:02.982762Z","steps":["trace[1294400398] 'process raft request' (duration: 169.68633ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:03.311806Z","caller":"traceutil/trace.go:171","msg":"trace[934972945] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"115.681906ms","start":"2026-03-17T21:58:03.196105Z","end":"2026-03-17T21:58:03.311787Z","steps":["trace[934972945] 'read index received' (duration: 115.560424ms)","trace[934972945] 'applied index is now lower than readState.Index' (duration: 120.582µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:03.311944Z","caller":"traceutil/trace.go:171","msg":"trace[122510005] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"294.939486ms","start":"2026-03-17T21:58:03.016974Z","end":"2026-03-17T21:58:03.311914Z","steps":["trace[122510005] 'process raft request' (duration: 294.696301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:03.312051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.888481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:03.312213Z","caller":"traceutil/trace.go:171","msg":"trace[1971856600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"116.120376ms","start":"2026-03-17T21:58:03.196066Z","end":"2026-03-17T21:58:03.312187Z","steps":["trace[1971856600] 'agreement among raft nodes before linearized reading' (duration: 115.876281ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:03.492592Z","caller":"traceutil/trace.go:171","msg":"trace[429534986] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"145.276465ms","start":"2026-03-17T21:58:03.347288Z","end":"2026-03-17T21:58:03.492564Z","steps":["trace[429534986] 'process raft request' (duration: 60.540626ms)","trace[429534986] 'compare' (duration: 84.537785ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:03.49295Z","caller":"traceutil/trace.go:171","msg":"trace[1717756323] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"144.825436ms","start":"2026-03-17T21:58:03.348084Z","end":"2026-03-17T21:58:03.492909Z","steps":["trace[1717756323] 'process raft request' (duration: 144.739224ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:03.493031Z","caller":"traceutil/trace.go:171","msg":"trace[1443753690] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"145.381728ms","start":"2026-03-17T21:58:03.347625Z","end":"2026-03-17T21:58:03.493007Z","steps":["trace[1443753690] 'process raft request' (duration: 144.867907ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:03.892008Z","caller":"traceutil/trace.go:171","msg":"trace[1511690727] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"148.525016ms","start":"2026-03-17T21:58:03.743456Z","end":"2026-03-17T21:58:03.891981Z","steps":["trace[1511690727] 'process raft request' (duration: 148.372943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:04.786962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:04.956141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055742817s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:58:04.956496Z","caller":"traceutil/trace.go:171","msg":"trace[1123523932] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.056164076s","start":"2026-03-17T21:58:03.900313Z","end":"2026-03-17T21:58:04.956477Z","steps":["trace[1123523932] 'process raft request' (duration: 1.056048544s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:04.956611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:03.900295Z","time spent":"1.056264088s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:06.261157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.9376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:06.261413Z","caller":"traceutil/trace.go:171","msg":"trace[913825305] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"961.259785ms","start":"2026-03-17T21:58:05.300135Z","end":"2026-03-17T21:58:06.261395Z","steps":["trace[913825305] 'process raft request' (duration: 961.190944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.261494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:05.300113Z","time spent":"961.338697ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:58:06.261797Z","caller":"traceutil/trace.go:171","msg":"trace[1215294045] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.299795938s","start":"2026-03-17T21:58:04.961989Z","end":"2026-03-17T21:58:06.261784Z","steps":["trace[1215294045] 'process raft request' (duration: 1.299267026s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:06.261805Z","caller":"traceutil/trace.go:171","msg":"trace[86551353] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1033; }","duration":"1.975673025s","start":"2026-03-17T21:58:04.286097Z","end":"2026-03-17T21:58:06.26177Z","steps":["trace[86551353] 'read index received' (duration: 670.271555ms)","trace[86551353] 'applied index is now lower than readState.Index' (duration: 1.30539723s)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:06.26183Z","caller":"traceutil/trace.go:171","msg":"trace[1087639191] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.683966051s","start":"2026-03-17T21:58:04.577809Z","end":"2026-03-17T21:58:06.261776Z","steps":["trace[1087639191] 'process raft request' (duration: 1.210343616s)","trace[1087639191] 'compare' (duration: 472.568362ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:06.261863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.961968Z","time spent":"1.299866689s","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:06.261973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.577779Z","time spent":"1.684123633s","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:06.263012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976893353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:06.263098Z","caller":"traceutil/trace.go:171","msg":"trace[220611239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"1.977017836s","start":"2026-03-17T21:58:04.286063Z","end":"2026-03-17T21:58:06.263081Z","steps":["trace[220611239] 'agreement among raft nodes before linearized reading' (duration: 1.976872133s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.263149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301047436s","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":"warn","ts":"2026-03-17T21:58:06.263156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.286047Z","time spent":"1.977094987s","remote":"127.0.0.1:43440","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-03-17T21:58:06.263017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06909696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:58:06.263225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.809893ms","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-03-17T21:58:06.263277Z","caller":"traceutil/trace.go:171","msg":"trace[987778422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"766.905845ms","start":"2026-03-17T21:58:05.496358Z","end":"2026-03-17T21:58:06.263264Z","steps":["trace[987778422] 'agreement among raft nodes before linearized reading' (duration: 766.751771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.263267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276223061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-17T21:58:06.263301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357326989s","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-03-17T21:58:06.263318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:05.496337Z","time spent":"766.973816ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-17T21:58:06.263329Z","caller":"traceutil/trace.go:171","msg":"trace[1404800764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.357400501s","start":"2026-03-17T21:58:04.905921Z","end":"2026-03-17T21:58:06.263321Z","steps":["trace[1404800764] 'agreement among raft nodes before linearized reading' (duration: 1.357312689s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:06.263337Z","caller":"traceutil/trace.go:171","msg":"trace[696706472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.276339104s","start":"2026-03-17T21:58:04.98698Z","end":"2026-03-17T21:58:06.263319Z","steps":["trace[696706472] 'agreement among raft nodes before linearized reading' (duration: 1.276113369s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.263361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.905897Z","time spent":"1.357456322s","remote":"127.0.0.1:43718","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-03-17T21:58:06.2632Z","caller":"traceutil/trace.go:171","msg":"trace[1293881467] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:991; }","duration":"1.301150858s","start":"2026-03-17T21:58:04.962035Z","end":"2026-03-17T21:58:06.263186Z","steps":["trace[1293881467] 'agreement among raft nodes before linearized reading' (duration: 1.300976764s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.263402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.98696Z","time spent":"1.276425135s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:06.26344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750406127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.199\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T21:58:06.263254Z","caller":"traceutil/trace.go:171","msg":"trace[1032645100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"1.069376116s","start":"2026-03-17T21:58:05.193859Z","end":"2026-03-17T21:58:06.263235Z","steps":["trace[1032645100] 'agreement among raft nodes before linearized reading' (duration: 1.06910687s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:06.263516Z","caller":"traceutil/trace.go:171","msg":"trace[1141608437] range","detail":"{range_begin:/registry/masterleases/199.19.213.199; range_end:; response_count:1; response_revision:991; }","duration":"1.75054321s","start":"2026-03-17T21:58:04.512953Z","end":"2026-03-17T21:58:06.263496Z","steps":["trace[1141608437] 'agreement among raft nodes before linearized reading' (duration: 1.750327075s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.263511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.962018Z","time spent":"1.301451334s","remote":"127.0.0.1:43718","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-03-17T21:58:06.26357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:04.51292Z","time spent":"1.750638552s","remote":"127.0.0.1:43480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.199\" "} {"level":"warn","ts":"2026-03-17T21:58:06.263562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:05.193841Z","time spent":"1.069712584s","remote":"127.0.0.1:43454","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-03-17T21:58:06.264847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.724732ms","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-03-17T21:58:06.264899Z","caller":"traceutil/trace.go:171","msg":"trace[994084523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"767.806534ms","start":"2026-03-17T21:58:05.49708Z","end":"2026-03-17T21:58:06.264886Z","steps":["trace[994084523] 'agreement among raft nodes before linearized reading' (duration: 765.970354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.264958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:05.497063Z","time spent":"767.886935ms","remote":"127.0.0.1:43718","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-03-17T21:58:06.265419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.159641ms","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-03-17T21:58:06.265471Z","caller":"traceutil/trace.go:171","msg":"trace[1715788571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"768.231583ms","start":"2026-03-17T21:58:05.497225Z","end":"2026-03-17T21:58:06.265457Z","steps":["trace[1715788571] 'agreement among raft nodes before linearized reading' (duration: 767.256832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:06.2655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:05.497217Z","time spent":"768.275204ms","remote":"127.0.0.1:43718","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-03-17T21:58:07.245377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.130169ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-03-17T21:58:07.245473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.265052Z","time spent":"980.417466ms","remote":"127.0.0.1:43480","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-17T21:58:07.282137Z","caller":"traceutil/trace.go:171","msg":"trace[511823364] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.014089124s","start":"2026-03-17T21:58:06.26802Z","end":"2026-03-17T21:58:07.282109Z","steps":["trace[511823364] 'process raft request' (duration: 1.013983461s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:07.282465Z","caller":"traceutil/trace.go:171","msg":"trace[1217076407] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.013137382s","start":"2026-03-17T21:58:06.269317Z","end":"2026-03-17T21:58:07.282455Z","steps":["trace[1217076407] 'process raft request' (duration: 1.012753514s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:07.28254Z","caller":"traceutil/trace.go:171","msg":"trace[2141017641] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1037; }","duration":"1.010548826s","start":"2026-03-17T21:58:06.271971Z","end":"2026-03-17T21:58:07.28252Z","steps":["trace[2141017641] 'read index received' (duration: 352.278814ms)","trace[2141017641] 'applied index is now lower than readState.Index' (duration: 658.268792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:07.282445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.268003Z","time spent":"1.014310698s","remote":"127.0.0.1:43718","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-03-17T21:58:07.282598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.269307Z","time spent":"1.013210864s","remote":"127.0.0.1:43718","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-03-17T21:58:07.282758Z","caller":"traceutil/trace.go:171","msg":"trace[1922711469] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.012849567s","start":"2026-03-17T21:58:06.269877Z","end":"2026-03-17T21:58:07.282726Z","steps":["trace[1922711469] 'process raft request' (duration: 1.01254956s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:07.282891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010922014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:07.282934Z","caller":"traceutil/trace.go:171","msg":"trace[774898595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"1.010996106s","start":"2026-03-17T21:58:06.271927Z","end":"2026-03-17T21:58:07.282923Z","steps":["trace[774898595] 'agreement among raft nodes before linearized reading' (duration: 1.010783012s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:07.282931Z","caller":"traceutil/trace.go:171","msg":"trace[2073593734] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.013522281s","start":"2026-03-17T21:58:06.269398Z","end":"2026-03-17T21:58:07.28292Z","steps":["trace[2073593734] 'process raft request' (duration: 1.012890667s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:07.282964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.271912Z","time spent":"1.011047187s","remote":"127.0.0.1:43440","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-03-17T21:58:07.28303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.269392Z","time spent":"1.013574182s","remote":"127.0.0.1:43718","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-03-17T21:58:07.283162Z","caller":"traceutil/trace.go:171","msg":"trace[1069679047] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.010950075s","start":"2026-03-17T21:58:06.272196Z","end":"2026-03-17T21:58:07.283146Z","steps":["trace[1069679047] 'process raft request' (duration: 1.010260569s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:07.282409Z","caller":"traceutil/trace.go:171","msg":"trace[285949534] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.015626917s","start":"2026-03-17T21:58:06.266759Z","end":"2026-03-17T21:58:07.282386Z","steps":["trace[285949534] 'process raft request' (duration: 1.015085955s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:07.283248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.272186Z","time spent":"1.011020596s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:07.283322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.266739Z","time spent":"1.016491546s","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:58:07.283375Z","caller":"traceutil/trace.go:171","msg":"trace[1881883024] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.010208689s","start":"2026-03-17T21:58:06.272419Z","end":"2026-03-17T21:58:07.282627Z","steps":["trace[1881883024] 'process raft request' (duration: 1.010064425s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:07.283653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.272411Z","time spent":"1.011161519s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:07.283503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:06.269867Z","time spent":"1.012966199s","remote":"127.0.0.1:43718","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-03-17T21:58:07.997608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.267418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:07.997758Z","caller":"traceutil/trace.go:171","msg":"trace[89186381] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"712.190694ms","start":"2026-03-17T21:58:07.285548Z","end":"2026-03-17T21:58:07.997738Z","steps":["trace[89186381] 'read index received' (duration: 128.632539ms)","trace[89186381] 'applied index is now lower than readState.Index' (duration: 583.556035ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:07.997866Z","caller":"traceutil/trace.go:171","msg":"trace[135611298] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"751.273851ms","start":"2026-03-17T21:58:07.246576Z","end":"2026-03-17T21:58:07.99785Z","steps":["trace[135611298] 'process raft request' (duration: 167.690316ms)","trace[135611298] 'compare' (duration: 583.080814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:07.997954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:07.246555Z","time spent":"751.347173ms","remote":"127.0.0.1:43480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:07.998435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.484725ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:58:07.99849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.91825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:07.998524Z","caller":"traceutil/trace.go:171","msg":"trace[557190461] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:998; }","duration":"471.833444ms","start":"2026-03-17T21:58:07.526678Z","end":"2026-03-17T21:58:07.998511Z","steps":["trace[557190461] 'range keys from in-memory index tree' (duration: 470.470795ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:07.998572Z","caller":"traceutil/trace.go:171","msg":"trace[1034381127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"713.028423ms","start":"2026-03-17T21:58:07.285521Z","end":"2026-03-17T21:58:07.998549Z","steps":["trace[1034381127] 'agreement among raft nodes before linearized reading' (duration: 712.707646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:07.998636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:07.285507Z","time spent":"713.115744ms","remote":"127.0.0.1:43454","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-03-17T21:58:08.504111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:08.906618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.409251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:08.906727Z","caller":"traceutil/trace.go:171","msg":"trace[1008723400] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"903.500558ms","start":"2026-03-17T21:58:08.003209Z","end":"2026-03-17T21:58:08.90671Z","steps":["trace[1008723400] 'read index received' (duration: 568.78474ms)","trace[1008723400] 'applied index is now lower than readState.Index' (duration: 334.714748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:08.907033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.169565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:08.90708Z","caller":"traceutil/trace.go:171","msg":"trace[796104055] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"904.201983ms","start":"2026-03-17T21:58:08.002863Z","end":"2026-03-17T21:58:08.907065Z","steps":["trace[796104055] 'process raft request' (duration: 569.2559ms)","trace[796104055] 'compare' (duration: 334.287629ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:08.907111Z","caller":"traceutil/trace.go:171","msg":"trace[1656011347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"900.270718ms","start":"2026-03-17T21:58:08.006823Z","end":"2026-03-17T21:58:08.907093Z","steps":["trace[1656011347] 'agreement among raft nodes before linearized reading' (duration: 900.139345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:08.907156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.933488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-17T21:58:08.907188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:08.002835Z","time spent":"904.313666ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:08.907161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:08.006816Z","time spent":"900.336859ms","remote":"127.0.0.1:43440","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-03-17T21:58:08.907224Z","caller":"traceutil/trace.go:171","msg":"trace[638318848] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1000; }","duration":"904.030709ms","start":"2026-03-17T21:58:08.003178Z","end":"2026-03-17T21:58:08.907209Z","steps":["trace[638318848] 'agreement among raft nodes before linearized reading' (duration: 903.898876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:08.907514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:08.003167Z","time spent":"904.325107ms","remote":"127.0.0.1:43734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-17T21:58:09.445761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.149989ms","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-03-17T21:58:09.445839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.599506ms","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-03-17T21:58:09.445854Z","caller":"traceutil/trace.go:171","msg":"trace[1476439886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"159.282112ms","start":"2026-03-17T21:58:09.286548Z","end":"2026-03-17T21:58:09.445831Z","steps":["trace[1476439886] 'range keys from in-memory index tree' (duration: 158.902084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:09.445878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.153738ms","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-03-17T21:58:09.445893Z","caller":"traceutil/trace.go:171","msg":"trace[438048991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"157.657707ms","start":"2026-03-17T21:58:09.288222Z","end":"2026-03-17T21:58:09.44588Z","steps":["trace[438048991] 'range keys from in-memory index tree' (duration: 157.469713ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:09.445941Z","caller":"traceutil/trace.go:171","msg":"trace[252624256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"158.2581ms","start":"2026-03-17T21:58:09.287668Z","end":"2026-03-17T21:58:09.445926Z","steps":["trace[252624256] 'range keys from in-memory index tree' (duration: 158.017635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:09.445761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.869859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:09.446028Z","caller":"traceutil/trace.go:171","msg":"trace[1443403112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"250.187736ms","start":"2026-03-17T21:58:09.195827Z","end":"2026-03-17T21:58:09.446015Z","steps":["trace[1443403112] 'range keys from in-memory index tree' (duration: 249.783256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:09.446036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.301181ms","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-03-17T21:58:09.446103Z","caller":"traceutil/trace.go:171","msg":"trace[1746474639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"158.392104ms","start":"2026-03-17T21:58:09.287693Z","end":"2026-03-17T21:58:09.446085Z","steps":["trace[1746474639] 'range keys from in-memory index tree' (duration: 158.141798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:09.446378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.257923ms","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-03-17T21:58:09.446443Z","caller":"traceutil/trace.go:171","msg":"trace[1002659585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"160.360756ms","start":"2026-03-17T21:58:09.286067Z","end":"2026-03-17T21:58:09.446428Z","steps":["trace[1002659585] 'range keys from in-memory index tree' (duration: 160.08895ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:09.612654Z","caller":"traceutil/trace.go:171","msg":"trace[1495436365] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"161.366427ms","start":"2026-03-17T21:58:09.451245Z","end":"2026-03-17T21:58:09.612612Z","steps":["trace[1495436365] 'process raft request' (duration: 161.238844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.052857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.051903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:11.053092Z","caller":"traceutil/trace.go:171","msg":"trace[1264468834] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.601772708s","start":"2026-03-17T21:58:09.451297Z","end":"2026-03-17T21:58:11.05307Z","steps":["trace[1264468834] 'process raft request' (duration: 1.075424048s)","trace[1264468834] 'compare' (duration: 525.609124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:11.053194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:09.451279Z","time spent":"1.601855639s","remote":"127.0.0.1:43718","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-03-17T21:58:11.053511Z","caller":"traceutil/trace.go:171","msg":"trace[1716424398] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.602135557s","start":"2026-03-17T21:58:09.451364Z","end":"2026-03-17T21:58:11.0535Z","steps":["trace[1716424398] 'process raft request' (duration: 1.601584744s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.053593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:09.451357Z","time spent":"1.602180448s","remote":"127.0.0.1:43718","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-03-17T21:58:11.05383Z","caller":"traceutil/trace.go:171","msg":"trace[1089090214] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.602394342s","start":"2026-03-17T21:58:09.451418Z","end":"2026-03-17T21:58:11.053812Z","steps":["trace[1089090214] 'process raft request' (duration: 1.601588633s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.053921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:09.451411Z","time spent":"1.602468044s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:58:11.05401Z","caller":"traceutil/trace.go:171","msg":"trace[1239372677] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.602224729s","start":"2026-03-17T21:58:09.451711Z","end":"2026-03-17T21:58:11.053936Z","steps":["trace[1239372677] 'process raft request' (duration: 1.601493013s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.054242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:09.451701Z","time spent":"1.602416952s","remote":"127.0.0.1:43718","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-03-17T21:58:11.996885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.598ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-17T21:58:11.996985Z","caller":"traceutil/trace.go:171","msg":"trace[1759325700] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1048; }","duration":"1.801789446s","start":"2026-03-17T21:58:10.195177Z","end":"2026-03-17T21:58:11.996967Z","steps":["trace[1759325700] 'read index received' (duration: 331.699423ms)","trace[1759325700] 'applied index is now lower than readState.Index' (duration: 1.470088583s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:11.99715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80197205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:11.997186Z","caller":"traceutil/trace.go:171","msg":"trace[1461917491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"1.802013881s","start":"2026-03-17T21:58:10.195163Z","end":"2026-03-17T21:58:11.997176Z","steps":["trace[1461917491] 'agreement among raft nodes before linearized reading' (duration: 1.801895578s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.997225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:10.195106Z","time spent":"1.802111764s","remote":"127.0.0.1:43454","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-03-17T21:58:11.997335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.90361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:58:11.997419Z","caller":"traceutil/trace.go:171","msg":"trace[1420623974] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1005; }","duration":"167.044464ms","start":"2026-03-17T21:58:11.830354Z","end":"2026-03-17T21:58:11.997398Z","steps":["trace[1420623974] 'agreement among raft nodes before linearized reading' (duration: 166.894579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.997389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.118374ms","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-03-17T21:58:11.997482Z","caller":"traceutil/trace.go:171","msg":"trace[1172046957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"380.262337ms","start":"2026-03-17T21:58:11.6172Z","end":"2026-03-17T21:58:11.997462Z","steps":["trace[1172046957] 'agreement among raft nodes before linearized reading' (duration: 380.030881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:11.997543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:11.61718Z","time spent":"380.350959ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-17T21:58:12.486302Z","caller":"traceutil/trace.go:171","msg":"trace[357620225] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"476.153416ms","start":"2026-03-17T21:58:12.010124Z","end":"2026-03-17T21:58:12.486277Z","steps":["trace[357620225] 'read index received' (duration: 475.918431ms)","trace[357620225] 'applied index is now lower than readState.Index' (duration: 232.055µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:12.486468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.365351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:12.486453Z","caller":"traceutil/trace.go:171","msg":"trace[1791657285] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"484.145773ms","start":"2026-03-17T21:58:12.002265Z","end":"2026-03-17T21:58:12.486411Z","steps":["trace[1791657285] 'process raft request' (duration: 483.756465ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:12.486499Z","caller":"traceutil/trace.go:171","msg":"trace[525327132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"476.499894ms","start":"2026-03-17T21:58:12.009989Z","end":"2026-03-17T21:58:12.486489Z","steps":["trace[525327132] 'agreement among raft nodes before linearized reading' (duration: 476.426342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:12.486536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:12.00997Z","time spent":"476.559225ms","remote":"127.0.0.1:43440","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-03-17T21:58:12.486626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:12.002243Z","time spent":"484.287656ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:12.486611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.592195ms","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-03-17T21:58:12.48668Z","caller":"traceutil/trace.go:171","msg":"trace[1743885874] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1006; }","duration":"195.706388ms","start":"2026-03-17T21:58:12.29096Z","end":"2026-03-17T21:58:12.486666Z","steps":["trace[1743885874] 'agreement among raft nodes before linearized reading' (duration: 195.514184ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:12.666333Z","caller":"traceutil/trace.go:171","msg":"trace[57336409] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"174.304763ms","start":"2026-03-17T21:58:12.491984Z","end":"2026-03-17T21:58:12.666289Z","steps":["trace[57336409] 'process raft request' (duration: 86.690396ms)","trace[57336409] 'compare' (duration: 87.385332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:13.06902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.316092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T21:58:13.069107Z","caller":"traceutil/trace.go:171","msg":"trace[1481128257] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1007; }","duration":"145.464325ms","start":"2026-03-17T21:58:12.923624Z","end":"2026-03-17T21:58:13.069089Z","steps":["trace[1481128257] 'count revisions from in-memory index tree' (duration: 144.996575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:13.696076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:14.197138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:14.698146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000897944144912592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:14.82364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.686940952s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:58:14.824367Z","caller":"traceutil/trace.go:171","msg":"trace[618758105] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.749226859s","start":"2026-03-17T21:58:13.075045Z","end":"2026-03-17T21:58:14.824272Z","steps":["trace[618758105] 'process raft request' (duration: 1.749188338s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:14.8245Z","caller":"traceutil/trace.go:171","msg":"trace[868947081] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.750188761s","start":"2026-03-17T21:58:13.074115Z","end":"2026-03-17T21:58:14.824304Z","steps":["trace[868947081] 'process raft request' (duration: 1.749756381s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:14.824599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.074091Z","time spent":"1.750445026s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:58:14.824624Z","caller":"traceutil/trace.go:171","msg":"trace[1457980981] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.75018513s","start":"2026-03-17T21:58:13.074133Z","end":"2026-03-17T21:58:14.824318Z","steps":["trace[1457980981] 'process raft request' (duration: 1.750055227s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:14.824793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.074111Z","time spent":"1.750577159s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:14.824934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.075036Z","time spent":"1.749429733s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:15.100446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.563599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:15.100593Z","caller":"traceutil/trace.go:171","msg":"trace[440583881] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1056; }","duration":"1.905394981s","start":"2026-03-17T21:58:13.195176Z","end":"2026-03-17T21:58:15.100571Z","steps":["trace[440583881] 'read index received' (duration: 1.628803988s)","trace[440583881] 'applied index is now lower than readState.Index' (duration: 276.589313ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:15.100738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905568754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:15.100775Z","caller":"traceutil/trace.go:171","msg":"trace[146639090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"1.905641066s","start":"2026-03-17T21:58:13.195122Z","end":"2026-03-17T21:58:15.100763Z","steps":["trace[146639090] 'agreement among raft nodes before linearized reading' (duration: 1.905575695s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:15.100747Z","caller":"traceutil/trace.go:171","msg":"trace[1078127447] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.927437418s","start":"2026-03-17T21:58:13.173228Z","end":"2026-03-17T21:58:15.100666Z","steps":["trace[1078127447] 'process raft request' (duration: 1.799538241s)","trace[1078127447] 'compare' (duration: 127.383046ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:15.100814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.1951Z","time spent":"1.905706467s","remote":"127.0.0.1:43440","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-03-17T21:58:15.100975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.173197Z","time spent":"1.927654693s","remote":"127.0.0.1:43718","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-03-17T21:58:15.101009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.107239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.199\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-17T21:58:15.101051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141618476s","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-03-17T21:58:15.101028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189179458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-03-17T21:58:15.101069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.622698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:15.101088Z","caller":"traceutil/trace.go:171","msg":"trace[2023054687] range","detail":"{range_begin:/registry/masterleases/199.19.213.199; range_end:; response_count:1; response_revision:1012; }","duration":"587.236982ms","start":"2026-03-17T21:58:14.513831Z","end":"2026-03-17T21:58:15.101068Z","steps":["trace[2023054687] 'agreement among raft nodes before linearized reading' (duration: 586.987226ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:15.101097Z","caller":"traceutil/trace.go:171","msg":"trace[779662984] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1012; }","duration":"1.141721708s","start":"2026-03-17T21:58:13.959363Z","end":"2026-03-17T21:58:15.101084Z","steps":["trace[779662984] 'agreement among raft nodes before linearized reading' (duration: 1.141605815s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:15.101145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.959331Z","time spent":"1.14180552s","remote":"127.0.0.1:43816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-03-17T21:58:15.101135Z","caller":"traceutil/trace.go:171","msg":"trace[193206534] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"1.189344561s","start":"2026-03-17T21:58:13.911766Z","end":"2026-03-17T21:58:15.10111Z","steps":["trace[193206534] 'agreement among raft nodes before linearized reading' (duration: 1.188992743s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:15.101143Z","caller":"traceutil/trace.go:171","msg":"trace[315142014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"814.73627ms","start":"2026-03-17T21:58:14.286387Z","end":"2026-03-17T21:58:15.101123Z","steps":["trace[315142014] 'agreement among raft nodes before linearized reading' (duration: 814.564916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:15.101148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:14.513809Z","time spent":"587.330564ms","remote":"127.0.0.1:43480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.199\" "} {"level":"warn","ts":"2026-03-17T21:58:15.101196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:13.911738Z","time spent":"1.189444264s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-17T21:58:15.101199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:14.28637Z","time spent":"814.817273ms","remote":"127.0.0.1:43440","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-03-17T21:58:15.100915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.291601ms","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-03-17T21:58:15.101749Z","caller":"traceutil/trace.go:171","msg":"trace[320700064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"611.176091ms","start":"2026-03-17T21:58:14.490557Z","end":"2026-03-17T21:58:15.101733Z","steps":["trace[320700064] 'agreement among raft nodes before linearized reading' (duration: 610.23528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:15.101797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:14.490533Z","time spent":"611.247163ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-17T21:58:16.577682Z","caller":"traceutil/trace.go:171","msg":"trace[1881646571] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"163.055284ms","start":"2026-03-17T21:58:16.414564Z","end":"2026-03-17T21:58:16.577619Z","steps":["trace[1881646571] 'process raft request' (duration: 162.866059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:17.223407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.693254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:17.223652Z","caller":"traceutil/trace.go:171","msg":"trace[1522645048] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"156.155132ms","start":"2026-03-17T21:58:17.067478Z","end":"2026-03-17T21:58:17.223633Z","steps":["trace[1522645048] 'process raft request' (duration: 156.04116ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:17.223934Z","caller":"traceutil/trace.go:171","msg":"trace[1229789314] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"158.499213ms","start":"2026-03-17T21:58:17.065406Z","end":"2026-03-17T21:58:17.223905Z","steps":["trace[1229789314] 'process raft request' (duration: 53.234727ms)","trace[1229789314] 'compare' (duration: 104.549041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:17.572134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.984337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:17.572334Z","caller":"traceutil/trace.go:171","msg":"trace[684728247] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"343.80439ms","start":"2026-03-17T21:58:17.228512Z","end":"2026-03-17T21:58:17.572317Z","steps":["trace[684728247] 'process raft request' (duration: 343.734428ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:17.57237Z","caller":"traceutil/trace.go:171","msg":"trace[51661645] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"416.892116ms","start":"2026-03-17T21:58:17.155442Z","end":"2026-03-17T21:58:17.572334Z","steps":["trace[51661645] 'process raft request' (duration: 256.630503ms)","trace[51661645] 'compare' (duration: 159.842623ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:17.572408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:17.228489Z","time spent":"343.879762ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:17.5725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:17.155421Z","time spent":"417.012798ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:58:17.572505Z","caller":"traceutil/trace.go:171","msg":"trace[1170165522] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"348.878032ms","start":"2026-03-17T21:58:17.223598Z","end":"2026-03-17T21:58:17.572476Z","steps":["trace[1170165522] 'read index received' (duration: 188.487596ms)","trace[1170165522] 'applied index is now lower than readState.Index' (duration: 160.385596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:17.572714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.740548ms","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-03-17T21:58:17.572746Z","caller":"traceutil/trace.go:171","msg":"trace[894702898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"386.79529ms","start":"2026-03-17T21:58:17.185941Z","end":"2026-03-17T21:58:17.572736Z","steps":["trace[894702898] 'agreement among raft nodes before linearized reading' (duration: 386.621396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:17.572781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:17.185891Z","time spent":"386.882131ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T21:58:18.001842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.363021ms","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-03-17T21:58:18.001938Z","caller":"traceutil/trace.go:171","msg":"trace[2017698988] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1029; }","duration":"329.533903ms","start":"2026-03-17T21:58:17.67238Z","end":"2026-03-17T21:58:18.001914Z","steps":["trace[2017698988] 'range keys from in-memory index tree' (duration: 329.166726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:18.002118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:17.672358Z","time spent":"329.615976ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-17T21:58:22.080949Z","caller":"traceutil/trace.go:171","msg":"trace[1135286749] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"126.425265ms","start":"2026-03-17T21:58:21.954492Z","end":"2026-03-17T21:58:22.080917Z","steps":["trace[1135286749] 'process raft request' (duration: 42.946499ms)","trace[1135286749] 'compare' (duration: 83.012906ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:22.08104Z","caller":"traceutil/trace.go:171","msg":"trace[152552426] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"125.431973ms","start":"2026-03-17T21:58:21.955585Z","end":"2026-03-17T21:58:22.081017Z","steps":["trace[152552426] 'process raft request' (duration: 125.079805ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:22.081344Z","caller":"traceutil/trace.go:171","msg":"trace[1736645158] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"119.8443ms","start":"2026-03-17T21:58:21.961488Z","end":"2026-03-17T21:58:22.081332Z","steps":["trace[1736645158] 'process raft request' (duration: 119.230296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.132251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.839833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:23.132492Z","caller":"traceutil/trace.go:171","msg":"trace[750056289] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"176.812709ms","start":"2026-03-17T21:58:22.955656Z","end":"2026-03-17T21:58:23.132468Z","steps":["trace[750056289] 'process raft request' (duration: 54.657469ms)","trace[750056289] 'compare' (duration: 121.72245ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:23.132664Z","caller":"traceutil/trace.go:171","msg":"trace[1863750870] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"101.475013ms","start":"2026-03-17T21:58:23.031175Z","end":"2026-03-17T21:58:23.13265Z","steps":["trace[1863750870] 'read index received' (duration: 51.191µs)","trace[1863750870] 'applied index is now lower than readState.Index' (duration: 101.422842ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:23.132706Z","caller":"traceutil/trace.go:171","msg":"trace[684245728] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"176.972712ms","start":"2026-03-17T21:58:22.955704Z","end":"2026-03-17T21:58:23.132677Z","steps":["trace[684245728] 'process raft request' (duration: 176.680626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.133029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.821421ms","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-03-17T21:58:23.133738Z","caller":"traceutil/trace.go:171","msg":"trace[392440082] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1065; }","duration":"101.983275ms","start":"2026-03-17T21:58:23.031123Z","end":"2026-03-17T21:58:23.133106Z","steps":["trace[392440082] 'agreement among raft nodes before linearized reading' (duration: 101.583155ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:23.727563Z","caller":"traceutil/trace.go:171","msg":"trace[1132730528] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"123.828467ms","start":"2026-03-17T21:58:23.603707Z","end":"2026-03-17T21:58:23.727535Z","steps":["trace[1132730528] 'process raft request' (duration: 123.51444ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:23.84079Z","caller":"traceutil/trace.go:171","msg":"trace[134228728] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"108.968639ms","start":"2026-03-17T21:58:23.731796Z","end":"2026-03-17T21:58:23.840765Z","steps":["trace[134228728] 'process raft request' (duration: 108.809435ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:27.12276Z","caller":"traceutil/trace.go:171","msg":"trace[1178909125] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"137.592931ms","start":"2026-03-17T21:58:26.985141Z","end":"2026-03-17T21:58:27.122734Z","steps":["trace[1178909125] 'read index received' (duration: 137.366396ms)","trace[1178909125] 'applied index is now lower than readState.Index' (duration: 225.215µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:27.122841Z","caller":"traceutil/trace.go:171","msg":"trace[2095353793] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"151.903258ms","start":"2026-03-17T21:58:26.970903Z","end":"2026-03-17T21:58:27.122806Z","steps":["trace[2095353793] 'process raft request' (duration: 151.663652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:27.123037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.874718ms","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-03-17T21:58:27.12312Z","caller":"traceutil/trace.go:171","msg":"trace[99640870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"138.031571ms","start":"2026-03-17T21:58:26.985069Z","end":"2026-03-17T21:58:27.123101Z","steps":["trace[99640870] 'agreement among raft nodes before linearized reading' (duration: 137.765055ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:31.321142Z","caller":"traceutil/trace.go:171","msg":"trace[1330564299] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"133.855598ms","start":"2026-03-17T21:58:31.187248Z","end":"2026-03-17T21:58:31.321104Z","steps":["trace[1330564299] 'process raft request' (duration: 133.774386ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:31.321225Z","caller":"traceutil/trace.go:171","msg":"trace[1175119475] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"134.025693ms","start":"2026-03-17T21:58:31.187134Z","end":"2026-03-17T21:58:31.321159Z","steps":["trace[1175119475] 'process raft request' (duration: 133.547381ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:53.648698Z","caller":"traceutil/trace.go:171","msg":"trace[1363940292] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"155.055218ms","start":"2026-03-17T21:58:53.493603Z","end":"2026-03-17T21:58:53.648658Z","steps":["trace[1363940292] 'process raft request' (duration: 58.82044ms)","trace[1363940292] 'compare' (duration: 96.010473ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:05.808821Z","caller":"traceutil/trace.go:171","msg":"trace[1973684010] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"106.506015ms","start":"2026-03-17T21:59:05.702247Z","end":"2026-03-17T21:59:05.808753Z","steps":["trace[1973684010] 'process raft request' (duration: 99.50643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:07.638464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.506996ms","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-03-17T21:59:07.638623Z","caller":"traceutil/trace.go:171","msg":"trace[1028665344] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1244; }","duration":"111.69226ms","start":"2026-03-17T21:59:07.52691Z","end":"2026-03-17T21:59:07.638603Z","steps":["trace[1028665344] 'range keys from in-memory index tree' (duration: 111.486646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:07.63887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.373739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:59:07.639007Z","caller":"traceutil/trace.go:171","msg":"trace[1032123756] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"156.98112ms","start":"2026-03-17T21:59:07.482001Z","end":"2026-03-17T21:59:07.638982Z","steps":["trace[1032123756] 'process raft request' (duration: 47.357476ms)","trace[1032123756] 'compare' (duration: 109.208215ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:08.350172Z","caller":"traceutil/trace.go:171","msg":"trace[1393116493] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"104.176103ms","start":"2026-03-17T21:59:08.245962Z","end":"2026-03-17T21:59:08.350138Z","steps":["trace[1393116493] 'process raft request' (duration: 103.893207ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:14.071184Z","caller":"traceutil/trace.go:171","msg":"trace[1846752838] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"103.063129ms","start":"2026-03-17T21:59:13.968097Z","end":"2026-03-17T21:59:14.07116Z","steps":["trace[1846752838] 'process raft request' (duration: 99.529521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:36.224582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.642007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-17T21:59:36.224701Z","caller":"traceutil/trace.go:171","msg":"trace[1301532266] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1360; }","duration":"106.808571ms","start":"2026-03-17T21:59:36.117869Z","end":"2026-03-17T21:59:36.224677Z","steps":["trace[1301532266] 'range keys from in-memory index tree' (duration: 106.407582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:36.993134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.188056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:59:36.993483Z","caller":"traceutil/trace.go:171","msg":"trace[822571853] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"312.864845ms","start":"2026-03-17T21:59:36.680595Z","end":"2026-03-17T21:59:36.99346Z","steps":["trace[822571853] 'process raft request' (duration: 126.24872ms)","trace[822571853] 'compare' (duration: 186.082134ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:36.99357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:36.680568Z","time spent":"312.967968ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:59:38.488808Z","caller":"traceutil/trace.go:171","msg":"trace[1622383202] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"191.094144ms","start":"2026-03-17T21:59:38.297676Z","end":"2026-03-17T21:59:38.488771Z","steps":["trace[1622383202] 'process raft request' (duration: 114.367988ms)","trace[1622383202] 'compare' (duration: 76.585513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:40.298901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.936307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:40.298995Z","caller":"traceutil/trace.go:171","msg":"trace[1312273491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"104.088831ms","start":"2026-03-17T21:59:40.194884Z","end":"2026-03-17T21:59:40.298973Z","steps":["trace[1312273491] 'range keys from in-memory index tree' (duration: 103.818415ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:52.839361Z","caller":"traceutil/trace.go:171","msg":"trace[365531043] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"104.115682ms","start":"2026-03-17T21:59:52.735211Z","end":"2026-03-17T21:59:52.839327Z","steps":["trace[365531043] 'process raft request' (duration: 103.878617ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:58.702235Z","caller":"traceutil/trace.go:171","msg":"trace[901190379] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"270.892488ms","start":"2026-03-17T21:59:58.431312Z","end":"2026-03-17T21:59:58.702205Z","steps":["trace[901190379] 'read index received' (duration: 237.137542ms)","trace[901190379] 'applied index is now lower than readState.Index' (duration: 33.753416ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:58.702261Z","caller":"traceutil/trace.go:171","msg":"trace[943616294] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"276.014962ms","start":"2026-03-17T21:59:58.426192Z","end":"2026-03-17T21:59:58.702207Z","steps":["trace[943616294] 'process raft request' (duration: 242.326818ms)","trace[943616294] 'compare' (duration: 33.404658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:58.702442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.111974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:59:58.702507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.144607ms","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-03-17T21:59:58.702511Z","caller":"traceutil/trace.go:171","msg":"trace[683938710] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1451; }","duration":"271.239507ms","start":"2026-03-17T21:59:58.431251Z","end":"2026-03-17T21:59:58.70249Z","steps":["trace[683938710] 'agreement among raft nodes before linearized reading' (duration: 271.089263ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:58.702544Z","caller":"traceutil/trace.go:171","msg":"trace[179085978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1451; }","duration":"187.239439ms","start":"2026-03-17T21:59:58.515292Z","end":"2026-03-17T21:59:58.702532Z","steps":["trace[179085978] 'agreement among raft nodes before linearized reading' (duration: 187.077006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:58.702594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713234ms","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":"warn","ts":"2026-03-17T21:59:58.702669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.156517ms","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-03-17T21:59:58.70268Z","caller":"traceutil/trace.go:171","msg":"trace[613839659] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1451; }","duration":"108.841476ms","start":"2026-03-17T21:59:58.593812Z","end":"2026-03-17T21:59:58.702654Z","steps":["trace[613839659] 'agreement among raft nodes before linearized reading' (duration: 108.633942ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:58.702727Z","caller":"traceutil/trace.go:171","msg":"trace[1191044467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1451; }","duration":"144.241779ms","start":"2026-03-17T21:59:58.558467Z","end":"2026-03-17T21:59:58.702708Z","steps":["trace[1191044467] 'agreement among raft nodes before linearized reading' (duration: 144.060885ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:58.895664Z","caller":"traceutil/trace.go:171","msg":"trace[989066985] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"187.822152ms","start":"2026-03-17T21:59:58.70782Z","end":"2026-03-17T21:59:58.895642Z","steps":["trace[989066985] 'process raft request' (duration: 143.37138ms)","trace[989066985] 'compare' (duration: 44.011663ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:58.895824Z","caller":"traceutil/trace.go:171","msg":"trace[1184067669] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"187.880023ms","start":"2026-03-17T21:59:58.707935Z","end":"2026-03-17T21:59:58.895815Z","steps":["trace[1184067669] 'process raft request' (duration: 187.368242ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:59.100636Z","caller":"traceutil/trace.go:171","msg":"trace[261529912] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"111.71425ms","start":"2026-03-17T21:59:58.988896Z","end":"2026-03-17T21:59:59.10061Z","steps":["trace[261529912] 'read index received' (duration: 46.671492ms)","trace[261529912] 'applied index is now lower than readState.Index' (duration: 65.039098ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:59.100893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.971315ms","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-03-17T21:59:59.100938Z","caller":"traceutil/trace.go:171","msg":"trace[514486995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1456; }","duration":"112.052037ms","start":"2026-03-17T21:59:58.988874Z","end":"2026-03-17T21:59:59.100926Z","steps":["trace[514486995] 'agreement among raft nodes before linearized reading' (duration: 111.886393ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:59.100872Z","caller":"traceutil/trace.go:171","msg":"trace[1358187571] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"112.493956ms","start":"2026-03-17T21:59:58.988322Z","end":"2026-03-17T21:59:59.100816Z","steps":["trace[1358187571] 'process raft request' (duration: 47.292576ms)","trace[1358187571] 'compare' (duration: 64.867363ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:00:03.077035Z","caller":"traceutil/trace.go:171","msg":"trace[725356878] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"199.293845ms","start":"2026-03-17T22:00:02.8777Z","end":"2026-03-17T22:00:03.076994Z","steps":["trace[725356878] 'process raft request' (duration: 115.822051ms)","trace[725356878] 'compare' (duration: 83.183688ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:00:03.077677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.445587ms","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-03-17T22:00:03.078774Z","caller":"traceutil/trace.go:171","msg":"trace[1980841126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1468; }","duration":"166.584861ms","start":"2026-03-17T22:00:02.912171Z","end":"2026-03-17T22:00:03.078756Z","steps":["trace[1980841126] 'agreement among raft nodes before linearized reading' (duration: 165.308664ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:03.077403Z","caller":"traceutil/trace.go:171","msg":"trace[212142785] linearizableReadLoop","detail":"{readStateIndex:1538; appliedIndex:1537; }","duration":"165.12456ms","start":"2026-03-17T22:00:02.91219Z","end":"2026-03-17T22:00:03.077314Z","steps":["trace[212142785] 'read index received' (duration: 81.371389ms)","trace[212142785] 'applied index is now lower than readState.Index' (duration: 83.751271ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:00:03.079718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.634554ms","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-03-17T22:00:03.079782Z","caller":"traceutil/trace.go:171","msg":"trace[2010519427] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1468; }","duration":"119.753326ms","start":"2026-03-17T22:00:02.960013Z","end":"2026-03-17T22:00:03.079766Z","steps":["trace[2010519427] 'agreement among raft nodes before linearized reading' (duration: 119.505931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:00:04.095278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.702374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T22:00:04.095401Z","caller":"traceutil/trace.go:171","msg":"trace[1626821744] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1473; }","duration":"113.881638ms","start":"2026-03-17T22:00:03.981499Z","end":"2026-03-17T22:00:04.09538Z","steps":["trace[1626821744] 'count revisions from in-memory index tree' (duration: 113.633553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:00:09.100049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.33079ms","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-03-17T22:00:09.100137Z","caller":"traceutil/trace.go:171","msg":"trace[829027411] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1492; }","duration":"125.480513ms","start":"2026-03-17T22:00:08.974635Z","end":"2026-03-17T22:00:09.100115Z","steps":["trace[829027411] 'range keys from in-memory index tree' (duration: 125.141436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:00:09.433938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.369981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-17T22:00:09.433998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.116909ms","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-03-17T22:00:09.434018Z","caller":"traceutil/trace.go:171","msg":"trace[1742172224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1496; }","duration":"139.513545ms","start":"2026-03-17T22:00:09.294486Z","end":"2026-03-17T22:00:09.434Z","steps":["trace[1742172224] 'range keys from in-memory index tree' (duration: 139.119896ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:09.434065Z","caller":"traceutil/trace.go:171","msg":"trace[1555688052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1496; }","duration":"112.231141ms","start":"2026-03-17T22:00:09.321817Z","end":"2026-03-17T22:00:09.434048Z","steps":["trace[1555688052] 'range keys from in-memory index tree' (duration: 111.949555ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:09.584523Z","caller":"traceutil/trace.go:171","msg":"trace[45050228] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"145.154488ms","start":"2026-03-17T22:00:09.439347Z","end":"2026-03-17T22:00:09.584502Z","steps":["trace[45050228] 'process raft request' (duration: 145.038465ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:09.585753Z","caller":"traceutil/trace.go:171","msg":"trace[1560187926] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"145.614729ms","start":"2026-03-17T22:00:09.440106Z","end":"2026-03-17T22:00:09.585721Z","steps":["trace[1560187926] 'process raft request' (duration: 145.481546ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:27.210213Z","caller":"traceutil/trace.go:171","msg":"trace[1252496196] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"137.354984ms","start":"2026-03-17T22:00:27.072811Z","end":"2026-03-17T22:00:27.210166Z","steps":["trace[1252496196] 'process raft request' (duration: 137.125009ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:29.400204Z","caller":"traceutil/trace.go:171","msg":"trace[1001030259] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"100.035368ms","start":"2026-03-17T22:00:29.300114Z","end":"2026-03-17T22:00:29.40015Z","steps":["trace[1001030259] 'process raft request' (duration: 65.766288ms)","trace[1001030259] 'compare' (duration: 33.986413ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:00:29.755072Z","caller":"traceutil/trace.go:171","msg":"trace[374583976] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"101.757367ms","start":"2026-03-17T22:00:29.65328Z","end":"2026-03-17T22:00:29.755038Z","steps":["trace[374583976] 'process raft request' (duration: 101.542412ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:29.813422Z","caller":"traceutil/trace.go:171","msg":"trace[1973524488] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1652; }","duration":"101.51133ms","start":"2026-03-17T22:00:29.711883Z","end":"2026-03-17T22:00:29.813394Z","steps":["trace[1973524488] 'read index received' (duration: 43.049944ms)","trace[1973524488] 'applied index is now lower than readState.Index' (duration: 58.459786ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:00:29.813733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.836148ms","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-03-17T22:00:29.813795Z","caller":"traceutil/trace.go:171","msg":"trace[2069476700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1578; }","duration":"101.959271ms","start":"2026-03-17T22:00:29.711821Z","end":"2026-03-17T22:00:29.813781Z","steps":["trace[2069476700] 'agreement among raft nodes before linearized reading' (duration: 101.662704ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:33.272955Z","caller":"traceutil/trace.go:171","msg":"trace[218858836] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"118.380964ms","start":"2026-03-17T22:00:33.154542Z","end":"2026-03-17T22:00:33.272923Z","steps":["trace[218858836] 'process raft request' (duration: 118.096888ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:33.466647Z","caller":"traceutil/trace.go:171","msg":"trace[656276012] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"142.339195ms","start":"2026-03-17T22:00:33.324286Z","end":"2026-03-17T22:00:33.466625Z","steps":["trace[656276012] 'process raft request' (duration: 60.687655ms)","trace[656276012] 'compare' (duration: 81.259751ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:00:34.046842Z","caller":"traceutil/trace.go:171","msg":"trace[638032886] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"119.884447ms","start":"2026-03-17T22:00:33.926911Z","end":"2026-03-17T22:00:34.046796Z","steps":["trace[638032886] 'process raft request' (duration: 46.910089ms)","trace[638032886] 'compare' (duration: 72.860396ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:00:34.593582Z","caller":"traceutil/trace.go:171","msg":"trace[1493520703] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"100.153771ms","start":"2026-03-17T22:00:34.493406Z","end":"2026-03-17T22:00:34.593559Z","steps":["trace[1493520703] 'process raft request' (duration: 99.513407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:00:50.445429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.986145ms","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-03-17T22:00:50.445442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.291098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:00:50.445515Z","caller":"traceutil/trace.go:171","msg":"trace[971744294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1660; }","duration":"243.094818ms","start":"2026-03-17T22:00:50.202404Z","end":"2026-03-17T22:00:50.445498Z","steps":["trace[971744294] 'range keys from in-memory index tree' (duration: 242.856523ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:50.445557Z","caller":"traceutil/trace.go:171","msg":"trace[1039466671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1660; }","duration":"250.443951ms","start":"2026-03-17T22:00:50.195077Z","end":"2026-03-17T22:00:50.445521Z","steps":["trace[1039466671] 'range keys from in-memory index tree' (duration: 250.208766ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:19.926207Z","caller":"traceutil/trace.go:171","msg":"trace[929288219] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"119.864007ms","start":"2026-03-17T22:01:19.806302Z","end":"2026-03-17T22:01:19.926166Z","steps":["trace[929288219] 'process raft request' (duration: 83.497511ms)","trace[929288219] 'compare' (duration: 36.233743ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:01:33.107411Z","caller":"traceutil/trace.go:171","msg":"trace[313426943] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"132.742372ms","start":"2026-03-17T22:01:32.974644Z","end":"2026-03-17T22:01:33.107386Z","steps":["trace[313426943] 'process raft request' (duration: 132.576978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:35.071935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.458629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:01:35.072055Z","caller":"traceutil/trace.go:171","msg":"trace[342922422] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"199.713157ms","start":"2026-03-17T22:01:34.87232Z","end":"2026-03-17T22:01:35.072033Z","steps":["trace[342922422] 'process raft request' (duration: 75.056643ms)","trace[342922422] 'compare' (duration: 124.301126ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:01:38.242065Z","caller":"traceutil/trace.go:171","msg":"trace[1045611056] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"230.641622ms","start":"2026-03-17T22:01:38.011386Z","end":"2026-03-17T22:01:38.242027Z","steps":["trace[1045611056] 'read index received' (duration: 230.354656ms)","trace[1045611056] 'applied index is now lower than readState.Index' (duration: 285.096µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:01:38.242181Z","caller":"traceutil/trace.go:171","msg":"trace[9796604] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"367.16093ms","start":"2026-03-17T22:01:37.87493Z","end":"2026-03-17T22:01:38.242091Z","steps":["trace[9796604] 'process raft request' (duration: 366.839963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:38.243263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.83882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-03-17T22:01:38.243274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:37.874906Z","time spent":"368.250374ms","remote":"127.0.0.1:43718","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-03-17T22:01:38.243298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.895771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-17T22:01:38.243353Z","caller":"traceutil/trace.go:171","msg":"trace[1120244952] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1878; }","duration":"167.973074ms","start":"2026-03-17T22:01:38.075356Z","end":"2026-03-17T22:01:38.243329Z","steps":["trace[1120244952] 'agreement among raft nodes before linearized reading' (duration: 167.098693ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:38.24336Z","caller":"traceutil/trace.go:171","msg":"trace[1255846473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1878; }","duration":"232.023784ms","start":"2026-03-17T22:01:38.01132Z","end":"2026-03-17T22:01:38.243344Z","steps":["trace[1255846473] 'agreement among raft nodes before linearized reading' (duration: 230.863598ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:45.232457Z","caller":"traceutil/trace.go:171","msg":"trace[1178282459] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"138.223925ms","start":"2026-03-17T22:01:45.094198Z","end":"2026-03-17T22:01:45.232422Z","steps":["trace[1178282459] 'read index received' (duration: 138.046661ms)","trace[1178282459] 'applied index is now lower than readState.Index' (duration: 176.534µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:01:45.232711Z","caller":"traceutil/trace.go:171","msg":"trace[307721464] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"142.777315ms","start":"2026-03-17T22:01:45.089925Z","end":"2026-03-17T22:01:45.232702Z","steps":["trace[307721464] 'process raft request' (duration: 142.383546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:45.232984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.778307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-17T22:01:45.233016Z","caller":"traceutil/trace.go:171","msg":"trace[695183202] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1914; }","duration":"138.841339ms","start":"2026-03-17T22:01:45.094165Z","end":"2026-03-17T22:01:45.233006Z","steps":["trace[695183202] 'agreement among raft nodes before linearized reading' (duration: 138.723206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.093474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.675318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:01:46.093886Z","caller":"traceutil/trace.go:171","msg":"trace[1290764163] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"803.820628ms","start":"2026-03-17T22:01:45.290019Z","end":"2026-03-17T22:01:46.09384Z","steps":["trace[1290764163] 'read index received' (duration: 187.66698ms)","trace[1290764163] 'applied index is now lower than readState.Index' (duration: 616.152458ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:01:46.094015Z","caller":"traceutil/trace.go:171","msg":"trace[669699604] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"855.952884ms","start":"2026-03-17T22:01:45.238036Z","end":"2026-03-17T22:01:46.093989Z","steps":["trace[669699604] 'process raft request' (duration: 239.58022ms)","trace[669699604] 'compare' (duration: 615.438163ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:01:46.094077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:45.238017Z","time spent":"856.029665ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:01:46.094169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.478542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T22:01:46.094187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.140005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:01:46.094272Z","caller":"traceutil/trace.go:171","msg":"trace[1206751998] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1915; }","duration":"804.263288ms","start":"2026-03-17T22:01:45.289987Z","end":"2026-03-17T22:01:46.09425Z","steps":["trace[1206751998] 'agreement among raft nodes before linearized reading' (duration: 804.119885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.094324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:45.289961Z","time spent":"804.35376ms","remote":"127.0.0.1:43696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-17T22:01:46.094219Z","caller":"traceutil/trace.go:171","msg":"trace[158268681] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1915; }","duration":"762.580343ms","start":"2026-03-17T22:01:45.331626Z","end":"2026-03-17T22:01:46.094206Z","steps":["trace[158268681] 'agreement among raft nodes before linearized reading' (duration: 762.489701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.094345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.843247ms","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-03-17T22:01:46.094431Z","caller":"traceutil/trace.go:171","msg":"trace[58821434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1915; }","duration":"747.97372ms","start":"2026-03-17T22:01:45.346437Z","end":"2026-03-17T22:01:46.094411Z","steps":["trace[58821434] 'agreement among raft nodes before linearized reading' (duration: 747.753305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.094483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:45.346416Z","time spent":"748.058082ms","remote":"127.0.0.1:43718","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-03-17T22:01:46.09447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:45.3316Z","time spent":"762.821909ms","remote":"127.0.0.1:43588","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-03-17T22:01:46.094543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.32258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-17T22:01:46.094608Z","caller":"traceutil/trace.go:171","msg":"trace[1909038637] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1915; }","duration":"126.388412ms","start":"2026-03-17T22:01:45.968198Z","end":"2026-03-17T22:01:46.094586Z","steps":["trace[1909038637] 'agreement among raft nodes before linearized reading' (duration: 125.977373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.623289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.380309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:01:46.623663Z","caller":"traceutil/trace.go:171","msg":"trace[398594752] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"523.622518ms","start":"2026-03-17T22:01:46.10002Z","end":"2026-03-17T22:01:46.623643Z","steps":["trace[398594752] 'process raft request' (duration: 523.462714ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:46.623662Z","caller":"traceutil/trace.go:171","msg":"trace[623204211] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"525.59578ms","start":"2026-03-17T22:01:46.09804Z","end":"2026-03-17T22:01:46.623636Z","steps":["trace[623204211] 'process raft request' (duration: 408.780242ms)","trace[623204211] 'compare' (duration: 116.031591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:01:46.623781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.813002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T22:01:46.623791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.100002Z","time spent":"523.715459ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:01:46.623663Z","caller":"traceutil/trace.go:171","msg":"trace[1728833098] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"523.691479ms","start":"2026-03-17T22:01:46.099936Z","end":"2026-03-17T22:01:46.623628Z","steps":["trace[1728833098] 'read index received' (duration: 406.903281ms)","trace[1728833098] 'applied index is now lower than readState.Index' (duration: 116.785908ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:01:46.623807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.098016Z","time spent":"525.713873ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:01:46.62383Z","caller":"traceutil/trace.go:171","msg":"trace[1739374625] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1917; }","duration":"523.912813ms","start":"2026-03-17T22:01:46.099902Z","end":"2026-03-17T22:01:46.623815Z","steps":["trace[1739374625] 'agreement among raft nodes before linearized reading' (duration: 523.806591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.62387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.099878Z","time spent":"523.983995ms","remote":"127.0.0.1:43588","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-03-17T22:01:46.623927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.705973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:01:46.623961Z","caller":"traceutil/trace.go:171","msg":"trace[1727198002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"428.780404ms","start":"2026-03-17T22:01:46.195169Z","end":"2026-03-17T22:01:46.62395Z","steps":["trace[1727198002] 'agreement among raft nodes before linearized reading' (duration: 428.719603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.623983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.237222ms","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-03-17T22:01:46.624028Z","caller":"traceutil/trace.go:171","msg":"trace[2048763752] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1917; }","duration":"331.306664ms","start":"2026-03-17T22:01:46.292711Z","end":"2026-03-17T22:01:46.624018Z","steps":["trace[2048763752] 'agreement among raft nodes before linearized reading' (duration: 331.180301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.624054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.2927Z","time spent":"331.347064ms","remote":"127.0.0.1:43718","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-03-17T22:01:46.624059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.475201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-17T22:01:46.624066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.880487ms","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-03-17T22:01:46.624093Z","caller":"traceutil/trace.go:171","msg":"trace[809159434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1917; }","duration":"345.938888ms","start":"2026-03-17T22:01:46.278146Z","end":"2026-03-17T22:01:46.624085Z","steps":["trace[809159434] 'agreement among raft nodes before linearized reading' (duration: 345.859967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.624114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.278129Z","time spent":"345.979159ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-17T22:01:46.62413Z","caller":"traceutil/trace.go:171","msg":"trace[1494771384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1917; }","duration":"342.509182ms","start":"2026-03-17T22:01:46.281573Z","end":"2026-03-17T22:01:46.624082Z","steps":["trace[1494771384] 'agreement among raft nodes before linearized reading' (duration: 342.384429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:46.623992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.195141Z","time spent":"428.844566ms","remote":"127.0.0.1:43440","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-03-17T22:01:46.624179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:01:46.281472Z","time spent":"342.692986ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T22:01:46.624304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.157231ms","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-03-17T22:01:46.624344Z","caller":"traceutil/trace.go:171","msg":"trace[788509656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1917; }","duration":"193.244024ms","start":"2026-03-17T22:01:46.43109Z","end":"2026-03-17T22:01:46.624334Z","steps":["trace[788509656] 'agreement among raft nodes before linearized reading' (duration: 193.070619ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:58.992456Z","caller":"traceutil/trace.go:171","msg":"trace[2037724678] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"243.224322ms","start":"2026-03-17T22:01:58.749205Z","end":"2026-03-17T22:01:58.992429Z","steps":["trace[2037724678] 'process raft request' (duration: 243.121099ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:58.992386Z","caller":"traceutil/trace.go:171","msg":"trace[308059517] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"243.231282ms","start":"2026-03-17T22:01:58.749094Z","end":"2026-03-17T22:01:58.992325Z","steps":["trace[308059517] 'process raft request' (duration: 242.336912ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:58.992547Z","caller":"traceutil/trace.go:171","msg":"trace[1914231980] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"242.171779ms","start":"2026-03-17T22:01:58.750356Z","end":"2026-03-17T22:01:58.992528Z","steps":["trace[1914231980] 'process raft request' (duration: 242.032336ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:58.992472Z","caller":"traceutil/trace.go:171","msg":"trace[82709266] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"243.345524ms","start":"2026-03-17T22:01:58.74911Z","end":"2026-03-17T22:01:58.992456Z","steps":["trace[82709266] 'process raft request' (duration: 243.132919ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:01:58.992537Z","caller":"traceutil/trace.go:171","msg":"trace[1322859769] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2068; }","duration":"170.864978ms","start":"2026-03-17T22:01:58.821608Z","end":"2026-03-17T22:01:58.992473Z","steps":["trace[1322859769] 'read index received' (duration: 169.829254ms)","trace[1322859769] 'applied index is now lower than readState.Index' (duration: 1.034984ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:01:58.992758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.092823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-03-17T22:01:58.992819Z","caller":"traceutil/trace.go:171","msg":"trace[1125225323] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1977; }","duration":"171.231836ms","start":"2026-03-17T22:01:58.821569Z","end":"2026-03-17T22:01:58.992801Z","steps":["trace[1125225323] 'agreement among raft nodes before linearized reading' (duration: 170.983181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:01:58.992835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.637685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-03-17T22:01:58.992877Z","caller":"traceutil/trace.go:171","msg":"trace[1961300539] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1977; }","duration":"166.728616ms","start":"2026-03-17T22:01:58.826134Z","end":"2026-03-17T22:01:58.992863Z","steps":["trace[1961300539] 'agreement among raft nodes before linearized reading' (duration: 166.623344ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:06.248074Z","caller":"traceutil/trace.go:171","msg":"trace[277408172] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"109.407316ms","start":"2026-03-17T22:02:06.138643Z","end":"2026-03-17T22:02:06.24805Z","steps":["trace[277408172] 'process raft request' (duration: 109.18423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:21.232487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.306321ms","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-03-17T22:02:21.232581Z","caller":"traceutil/trace.go:171","msg":"trace[761694434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"131.492075ms","start":"2026-03-17T22:02:21.101065Z","end":"2026-03-17T22:02:21.232557Z","steps":["trace[761694434] 'range keys from in-memory index tree' (duration: 131.148177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:21.232732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.909691ms","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-03-17T22:02:21.232831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.506208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fwmm4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-17T22:02:21.232841Z","caller":"traceutil/trace.go:171","msg":"trace[923055216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2063; }","duration":"126.033494ms","start":"2026-03-17T22:02:21.106764Z","end":"2026-03-17T22:02:21.232797Z","steps":["trace[923055216] 'range keys from in-memory index tree' (duration: 125.754027ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:21.2329Z","caller":"traceutil/trace.go:171","msg":"trace[940701595] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fwmm4; range_end:; response_count:1; response_revision:2063; }","duration":"220.602401ms","start":"2026-03-17T22:02:21.012272Z","end":"2026-03-17T22:02:21.232874Z","steps":["trace[940701595] 'range keys from in-memory index tree' (duration: 220.387766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:21.233178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.453136ms","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-03-17T22:02:21.233282Z","caller":"traceutil/trace.go:171","msg":"trace[1132323972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2063; }","duration":"113.620209ms","start":"2026-03-17T22:02:21.119637Z","end":"2026-03-17T22:02:21.233257Z","steps":["trace[1132323972] 'range keys from in-memory index tree' (duration: 112.830182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:21.512896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.830384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fwmm4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-17T22:02:21.513092Z","caller":"traceutil/trace.go:171","msg":"trace[318658561] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fwmm4; range_end:; response_count:1; response_revision:2068; }","duration":"174.076379ms","start":"2026-03-17T22:02:21.338995Z","end":"2026-03-17T22:02:21.513072Z","steps":["trace[318658561] 'range keys from in-memory index tree' (duration: 173.709891ms)"],"step_count":1}