{"level":"warn","ts":"2026-03-02T21:18:59.44821Z","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-02T21:18:59.448348Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-02T21:18:59.448461Z","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-02T21:18:59.448486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-03-02T21:18:59.448524Z","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-02T21:18:59.449743Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-03-02T21:18:59.449881Z","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.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-02T21:18:59.506404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"56.109897ms"} {"level":"info","ts":"2026-03-02T21:18:59.557951Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-03-02T21:18:59.558176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:18:59.558291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-03-02T21:18:59.558345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:18:59.558403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-03-02T21:18:59.558507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-03-02T21:18:59.589583Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:18:59.591993Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:18:59.621073Z","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-02T21:18:59.649745Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:18:59.650042Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:18:59.650199Z","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-02T21:18:59.651081Z","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-02T21:18:59.651114Z","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-02T21:18:59.656278Z","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-02T21:18:59.656409Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-03-02T21:18:59.656469Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-03-02T21:18:59.656765Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:18:59.656834Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:18:59.670049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-03-02T21:18:59.670241Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-03-02T21:18:59.670314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:18:59.67033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:18:59.670349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-03-02T21:18:59.670367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:18:59.670391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-03-02T21:18:59.670412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-03-02T21:18:59.670422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-03-02T21:18:59.673724Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:18:59.6738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:18:59.673864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:18:59.673999Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:18:59.674165Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:18:59.674208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:18:59.678213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-03-02T21:18:59.679267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:18:59.683426Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:18:59.683523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:18:59.68356Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-02T21:19:02.133867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755372035675514485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T21:19:02.214862Z","caller":"traceutil/trace.go:171","msg":"trace[1686173959] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"594.020997ms","start":"2026-03-02T21:19:01.62082Z","end":"2026-03-02T21:19:02.214841Z","steps":["trace[1686173959] 'process raft request' (duration: 593.81852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.215363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.620806Z","time spent":"594.158702ms","remote":"127.0.0.1:38838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:19:02.434299Z","caller":"traceutil/trace.go:171","msg":"trace[1670021219] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"737.529213ms","start":"2026-03-02T21:19:01.696737Z","end":"2026-03-02T21:19:02.434266Z","steps":["trace[1670021219] 'process raft request' (duration: 737.392278ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:02.434389Z","caller":"traceutil/trace.go:171","msg":"trace[1378268395] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"801.850831ms","start":"2026-03-02T21:19:01.632513Z","end":"2026-03-02T21:19:02.434364Z","steps":["trace[1378268395] 'read index received' (duration: 582.186031ms)","trace[1378268395] 'applied index is now lower than readState.Index' (duration: 219.66388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:02.434436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.696718Z","time spent":"737.657778ms","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:02.434739Z","caller":"traceutil/trace.go:171","msg":"trace[115037103] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"737.622137ms","start":"2026-03-02T21:19:01.697105Z","end":"2026-03-02T21:19:02.434727Z","steps":["trace[115037103] 'process raft request' (duration: 737.149219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.434853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.697087Z","time spent":"737.696999ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:02.434757Z","caller":"traceutil/trace.go:171","msg":"trace[2081917151] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"813.453998ms","start":"2026-03-02T21:19:01.621209Z","end":"2026-03-02T21:19:02.434663Z","steps":["trace[2081917151] 'process raft request' (duration: 812.758573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.434913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.267628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.435038Z","caller":"traceutil/trace.go:171","msg":"trace[909321743] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"764.562598ms","start":"2026-03-02T21:19:01.670462Z","end":"2026-03-02T21:19:02.435024Z","steps":["trace[909321743] 'agreement among raft nodes before linearized reading' (duration: 764.237486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.670441Z","time spent":"764.61609ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-02T21:19:02.435103Z","caller":"traceutil/trace.go:171","msg":"trace[1466978463] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"738.25534ms","start":"2026-03-02T21:19:01.696829Z","end":"2026-03-02T21:19:02.435084Z","steps":["trace[1466978463] 'process raft request' (duration: 737.362017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.696808Z","time spent":"738.324183ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:02.43515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.621202Z","time spent":"813.767341ms","remote":"127.0.0.1:38846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:19:02.435226Z","caller":"traceutil/trace.go:171","msg":"trace[1818997341] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"730.39173ms","start":"2026-03-02T21:19:01.704826Z","end":"2026-03-02T21:19:02.435217Z","steps":["trace[1818997341] 'process raft request' (duration: 729.499327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.704809Z","time spent":"730.438112ms","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:02.435323Z","caller":"traceutil/trace.go:171","msg":"trace[1263593735] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"738.301852ms","start":"2026-03-02T21:19:01.696971Z","end":"2026-03-02T21:19:02.435273Z","steps":["trace[1263593735] 'process raft request' (duration: 737.253983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.865239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.435437Z","caller":"traceutil/trace.go:171","msg":"trace[1700539635] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"802.946012ms","start":"2026-03-02T21:19:01.632484Z","end":"2026-03-02T21:19:02.43543Z","steps":["trace[1700539635] 'agreement among raft nodes before linearized reading' (duration: 802.872929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.632473Z","time spent":"802.978422ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-02T21:19:02.43556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.058422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.435577Z","caller":"traceutil/trace.go:171","msg":"trace[1707173944] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"653.096214ms","start":"2026-03-02T21:19:01.782475Z","end":"2026-03-02T21:19:02.435572Z","steps":["trace[1707173944] 'agreement among raft nodes before linearized reading' (duration: 653.067643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.782465Z","time spent":"653.123835ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-03-02T21:19:02.435583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.696951Z","time spent":"738.481588ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:02.435616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.1539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.435663Z","caller":"traceutil/trace.go:171","msg":"trace[1244010277] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"740.270234ms","start":"2026-03-02T21:19:01.695381Z","end":"2026-03-02T21:19:02.435651Z","steps":["trace[1244010277] 'agreement among raft nodes before linearized reading' (duration: 740.184761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.43575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.695361Z","time spent":"740.327816ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-02T21:19:02.435887Z","caller":"traceutil/trace.go:171","msg":"trace[1671710316] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"738.517989ms","start":"2026-03-02T21:19:01.697361Z","end":"2026-03-02T21:19:02.435879Z","steps":["trace[1671710316] 'process raft request' (duration: 736.936171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.435937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.697354Z","time spent":"738.55819ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:02.435949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.745018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.435993Z","caller":"traceutil/trace.go:171","msg":"trace[1041386026] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"738.81015ms","start":"2026-03-02T21:19:01.697168Z","end":"2026-03-02T21:19:02.435978Z","steps":["trace[1041386026] 'agreement among raft nodes before linearized reading' (duration: 738.734427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.436014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.855457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.436034Z","caller":"traceutil/trace.go:171","msg":"trace[1236646318] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"686.897969ms","start":"2026-03-02T21:19:01.74913Z","end":"2026-03-02T21:19:02.436028Z","steps":["trace[1236646318] 'agreement among raft nodes before linearized reading' (duration: 686.867388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.436027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.697161Z","time spent":"738.858912ms","remote":"127.0.0.1:38866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-02T21:19:02.436052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.749118Z","time spent":"686.930769ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-02T21:19:02.436133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.83209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:02.436168Z","caller":"traceutil/trace.go:171","msg":"trace[1365674224] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:0; response_revision:10; }","duration":"732.921564ms","start":"2026-03-02T21:19:01.703237Z","end":"2026-03-02T21:19:02.436158Z","steps":["trace[1365674224] 'agreement among raft nodes before linearized reading' (duration: 732.796949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.436214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.703225Z","time spent":"732.974625ms","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"info","ts":"2026-03-02T21:19:02.436351Z","caller":"traceutil/trace.go:171","msg":"trace[2094200720] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"739.0611ms","start":"2026-03-02T21:19:01.697264Z","end":"2026-03-02T21:19:02.436326Z","steps":["trace[2094200720] 'process raft request' (duration: 737.010904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.43649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:01.697235Z","time spent":"739.188324ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.114509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.882705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:4"} {"level":"info","ts":"2026-03-02T21:19:04.114897Z","caller":"traceutil/trace.go:171","msg":"trace[1845035252] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.660907976s","start":"2026-03-02T21:19:02.453954Z","end":"2026-03-02T21:19:04.114862Z","steps":["trace[1845035252] 'process raft request' (duration: 1.660636616s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.115168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.453929Z","time spent":"1.661167756s","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:04.115264Z","caller":"traceutil/trace.go:171","msg":"trace[301697118] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.659769274s","start":"2026-03-02T21:19:02.455488Z","end":"2026-03-02T21:19:04.115258Z","steps":["trace[301697118] 'process raft request' (duration: 1.659299077s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.115328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.455476Z","time spent":"1.659815156s","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:19:04.115343Z","caller":"traceutil/trace.go:171","msg":"trace[1719428933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"1.662340629s","start":"2026-03-02T21:19:02.452941Z","end":"2026-03-02T21:19:04.115282Z","steps":["trace[1719428933] 'process raft request' (duration: 773.506469ms)","trace[1719428933] 'compare' (duration: 887.833403ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:04.115564Z","caller":"traceutil/trace.go:171","msg":"trace[690580861] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"1.662505905s","start":"2026-03-02T21:19:02.453046Z","end":"2026-03-02T21:19:04.115552Z","steps":["trace[690580861] 'read index received' (duration: 14.984722ms)","trace[690580861] 'applied index is now lower than readState.Index' (duration: 1.647519853s)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:04.115636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.452919Z","time spent":"1.662609699s","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.11582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662770655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-03-02T21:19:04.115852Z","caller":"traceutil/trace.go:171","msg":"trace[1043141137] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"1.662827137s","start":"2026-03-02T21:19:02.453017Z","end":"2026-03-02T21:19:04.115844Z","steps":["trace[1043141137] 'agreement among raft nodes before linearized reading' (duration: 1.662763434s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:04.114932Z","caller":"traceutil/trace.go:171","msg":"trace[2099784717] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"904.528568ms","start":"2026-03-02T21:19:03.210389Z","end":"2026-03-02T21:19:04.114917Z","steps":["trace[2099784717] 'process raft request' (duration: 904.504697ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:04.115873Z","caller":"traceutil/trace.go:171","msg":"trace[1399158074] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.606601606s","start":"2026-03-02T21:19:02.509227Z","end":"2026-03-02T21:19:04.115804Z","steps":["trace[1399158074] 'process raft request' (duration: 1.605638291s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.11597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:03.210289Z","time spent":"905.646049ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.116174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.730559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.116201Z","caller":"traceutil/trace.go:171","msg":"trace[2116770579] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:20; }","duration":"950.801322ms","start":"2026-03-02T21:19:03.165393Z","end":"2026-03-02T21:19:04.116195Z","steps":["trace[2116770579] 'agreement among raft nodes before linearized reading' (duration: 950.75628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.116179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.509213Z","time spent":"1.606837025s","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.11622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:03.165361Z","time spent":"950.854704ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-02T21:19:04.116513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.581735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.116543Z","caller":"traceutil/trace.go:171","msg":"trace[140445302] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:20; }","duration":"975.612826ms","start":"2026-03-02T21:19:03.140922Z","end":"2026-03-02T21:19:04.116535Z","steps":["trace[140445302] 'agreement among raft nodes before linearized reading' (duration: 975.569414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.116562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:03.140857Z","time spent":"975.700689ms","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-03-02T21:19:04.114961Z","caller":"traceutil/trace.go:171","msg":"trace[2111719093] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.660821143s","start":"2026-03-02T21:19:02.454134Z","end":"2026-03-02T21:19:04.114955Z","steps":["trace[2111719093] 'process raft request' (duration: 1.660540413s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.115874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.453009Z","time spent":"1.662860769s","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-02T21:19:04.116906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.454125Z","time spent":"1.662638431s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:04.115Z","caller":"traceutil/trace.go:171","msg":"trace[1771989610] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.660812783s","start":"2026-03-02T21:19:02.454183Z","end":"2026-03-02T21:19:04.114995Z","steps":["trace[1771989610] 'process raft request' (duration: 1.660563744s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.117047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.45416Z","time spent":"1.662855978s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:04.115031Z","caller":"traceutil/trace.go:171","msg":"trace[1576917946] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.660761171s","start":"2026-03-02T21:19:02.454266Z","end":"2026-03-02T21:19:04.115016Z","steps":["trace[1576917946] 'process raft request' (duration: 1.660495811s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.116049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662896409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.117345Z","caller":"traceutil/trace.go:171","msg":"trace[841673417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.66424831s","start":"2026-03-02T21:19:02.45307Z","end":"2026-03-02T21:19:04.117319Z","steps":["trace[841673417] 'agreement among raft nodes before linearized reading' (duration: 1.662913111s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.117417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.453055Z","time spent":"1.664344153s","remote":"127.0.0.1:38778","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-02T21:19:04.11498Z","caller":"traceutil/trace.go:171","msg":"trace[506284060] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.660786192s","start":"2026-03-02T21:19:02.45419Z","end":"2026-03-02T21:19:04.114976Z","steps":["trace[506284060] 'process raft request' (duration: 1.660538523s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.117813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.454175Z","time spent":"1.663587575s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.11728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.454126Z","time spent":"1.663117288s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.118511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523549268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.118547Z","caller":"traceutil/trace.go:171","msg":"trace[235579924] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"1.523647451s","start":"2026-03-02T21:19:02.594891Z","end":"2026-03-02T21:19:04.118539Z","steps":["trace[235579924] 'agreement among raft nodes before linearized reading' (duration: 1.523579028s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.118568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.594865Z","time spent":"1.523698053s","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-02T21:19:04.118674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442351467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.118695Z","caller":"traceutil/trace.go:171","msg":"trace[1713097617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.442417049s","start":"2026-03-02T21:19:02.676272Z","end":"2026-03-02T21:19:04.118689Z","steps":["trace[1713097617] 'agreement among raft nodes before linearized reading' (duration: 1.442384688s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.118712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.676238Z","time spent":"1.442470281s","remote":"127.0.0.1:38786","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-02T21:19:04.11881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522750268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.118881Z","caller":"traceutil/trace.go:171","msg":"trace[1673128827] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"1.522862162s","start":"2026-03-02T21:19:02.596005Z","end":"2026-03-02T21:19:04.118867Z","steps":["trace[1673128827] 'agreement among raft nodes before linearized reading' (duration: 1.522679825s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.118953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.595993Z","time spent":"1.522949576s","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-02T21:19:04.118986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647492533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T21:19:04.11902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61349328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.119048Z","caller":"traceutil/trace.go:171","msg":"trace[1476608648] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"1.613538111s","start":"2026-03-02T21:19:02.505501Z","end":"2026-03-02T21:19:04.119039Z","steps":["trace[1476608648] 'agreement among raft nodes before linearized reading' (duration: 1.61349952s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:04.119044Z","caller":"traceutil/trace.go:171","msg":"trace[1838974195] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:20; }","duration":"1.647595586s","start":"2026-03-02T21:19:02.471434Z","end":"2026-03-02T21:19:04.119029Z","steps":["trace[1838974195] 'agreement among raft nodes before linearized reading' (duration: 1.647495332s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.119075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.505472Z","time spent":"1.613598524s","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-03-02T21:19:04.11909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.471388Z","time spent":"1.6476944s","remote":"127.0.0.1:38866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-03-02T21:19:04.118905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463871389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:04.119218Z","caller":"traceutil/trace.go:171","msg":"trace[1479120669] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"1.464224613s","start":"2026-03-02T21:19:02.654984Z","end":"2026-03-02T21:19:04.119209Z","steps":["trace[1479120669] 'agreement among raft nodes before linearized reading' (duration: 1.46389642s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.119244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.654954Z","time spent":"1.464283755s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-03-02T21:19:04.119469Z","caller":"traceutil/trace.go:171","msg":"trace[763683096] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.66532418s","start":"2026-03-02T21:19:02.454102Z","end":"2026-03-02T21:19:04.119426Z","steps":["trace[763683096] 'process raft request' (duration: 1.660552104s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.119614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.45408Z","time spent":"1.665485475s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:04.626274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755372035675514512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:19:05.926926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.018297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-02T21:19:05.927022Z","caller":"traceutil/trace.go:171","msg":"trace[1037862428] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"1.802362628s","start":"2026-03-02T21:19:04.124641Z","end":"2026-03-02T21:19:05.927003Z","steps":["trace[1037862428] 'process raft request' (duration: 962.209805ms)","trace[1037862428] 'compare' (duration: 839.781379ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:05.927073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.124621Z","time spent":"1.80243177s","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:05.92899Z","caller":"traceutil/trace.go:171","msg":"trace[1798891776] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"1.804111622s","start":"2026-03-02T21:19:04.12484Z","end":"2026-03-02T21:19:05.928952Z","steps":["trace[1798891776] 'process raft request' (duration: 1.803919635s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.929039Z","caller":"traceutil/trace.go:171","msg":"trace[960501464] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"1.802401969s","start":"2026-03-02T21:19:04.126623Z","end":"2026-03-02T21:19:05.929025Z","steps":["trace[960501464] 'process raft request' (duration: 1.802260554s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.126614Z","time spent":"1.802452242s","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:05.929118Z","caller":"traceutil/trace.go:171","msg":"trace[1527433152] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"1.802409559s","start":"2026-03-02T21:19:04.1267Z","end":"2026-03-02T21:19:05.929109Z","steps":["trace[1527433152] 'process raft request' (duration: 1.802239173s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.124827Z","time spent":"1.804241997s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:05.929173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.126693Z","time spent":"1.802460822s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:05.929162Z","caller":"traceutil/trace.go:171","msg":"trace[728306646] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:24; }","duration":"1.803765789s","start":"2026-03-02T21:19:04.125379Z","end":"2026-03-02T21:19:05.929145Z","steps":["trace[728306646] 'read index received' (duration: 961.485029ms)","trace[728306646] 'applied index is now lower than readState.Index' (duration: 842.27964ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:05.929225Z","caller":"traceutil/trace.go:171","msg":"trace[402902495] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"1.802450161s","start":"2026-03-02T21:19:04.126768Z","end":"2026-03-02T21:19:05.929219Z","steps":["trace[402902495] 'process raft request' (duration: 1.802200702s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.12676Z","time spent":"1.802481072s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:05.929289Z","caller":"traceutil/trace.go:171","msg":"trace[704472390] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"1.802010395s","start":"2026-03-02T21:19:04.127237Z","end":"2026-03-02T21:19:05.929248Z","steps":["trace[704472390] 'process raft request' (duration: 1.801848579s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.929376Z","caller":"traceutil/trace.go:171","msg":"trace[640138950] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"1.801657722s","start":"2026-03-02T21:19:04.127699Z","end":"2026-03-02T21:19:05.929357Z","steps":["trace[640138950] 'process raft request' (duration: 1.801409723s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.929416Z","caller":"traceutil/trace.go:171","msg":"trace[1861782069] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"1.802586436s","start":"2026-03-02T21:19:04.126823Z","end":"2026-03-02T21:19:05.929409Z","steps":["trace[1861782069] 'process raft request' (duration: 1.802180071s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.127228Z","time spent":"1.80214079s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:05.929469Z","caller":"traceutil/trace.go:171","msg":"trace[210485264] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"1.802619127s","start":"2026-03-02T21:19:04.126844Z","end":"2026-03-02T21:19:05.929463Z","steps":["trace[210485264] 'process raft request' (duration: 1.802193002s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.127672Z","time spent":"1.801748716s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:05.929498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.126836Z","time spent":"1.802646108s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:05.929569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801919082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:05.929601Z","caller":"traceutil/trace.go:171","msg":"trace[803833525] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"1.801963643s","start":"2026-03-02T21:19:04.127627Z","end":"2026-03-02T21:19:05.92959Z","steps":["trace[803833525] 'agreement among raft nodes before linearized reading' (duration: 1.801907931s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.12762Z","time spent":"1.801997475s","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-03-02T21:19:05.929643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70821681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T21:19:05.929651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751849528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:05.929662Z","caller":"traceutil/trace.go:171","msg":"trace[1201860843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30; }","duration":"1.708288412s","start":"2026-03-02T21:19:04.221367Z","end":"2026-03-02T21:19:05.929656Z","steps":["trace[1201860843] 'agreement among raft nodes before linearized reading' (duration: 1.708252961s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.929678Z","caller":"traceutil/trace.go:171","msg":"trace[1708477071] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:30; }","duration":"1.75192353s","start":"2026-03-02T21:19:04.177748Z","end":"2026-03-02T21:19:05.929671Z","steps":["trace[1708477071] 'agreement among raft nodes before linearized reading' (duration: 1.751845347s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.221355Z","time spent":"1.708325653s","remote":"127.0.0.1:38778","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-02T21:19:05.929697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.177732Z","time spent":"1.751960742s","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-node-lease\" "} {"level":"warn","ts":"2026-03-02T21:19:05.929686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.804289179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T21:19:05.929726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752652127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:05.929753Z","caller":"traceutil/trace.go:171","msg":"trace[1311907505] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:30; }","duration":"1.752697938s","start":"2026-03-02T21:19:04.177043Z","end":"2026-03-02T21:19:05.929741Z","steps":["trace[1311907505] 'agreement among raft nodes before linearized reading' (duration: 1.752663507s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.92975Z","caller":"traceutil/trace.go:171","msg":"trace[160579406] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:30; }","duration":"1.804368682s","start":"2026-03-02T21:19:04.125362Z","end":"2026-03-02T21:19:05.92973Z","steps":["trace[160579406] 'agreement among raft nodes before linearized reading' (duration: 1.804255478s)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:05.929575Z","caller":"traceutil/trace.go:171","msg":"trace[1254453764] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"1.802672079s","start":"2026-03-02T21:19:04.126895Z","end":"2026-03-02T21:19:05.929567Z","steps":["trace[1254453764] 'process raft request' (duration: 1.80216562s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.125355Z","time spent":"1.804428694s","remote":"127.0.0.1:39128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-02T21:19:05.929854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753104823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:19:05.929877Z","caller":"traceutil/trace.go:171","msg":"trace[296827377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30; }","duration":"1.753170736s","start":"2026-03-02T21:19:04.176701Z","end":"2026-03-02T21:19:05.929872Z","steps":["trace[296827377] 'agreement among raft nodes before linearized reading' (duration: 1.753138955s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:05.929892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.176681Z","time spent":"1.753208127s","remote":"127.0.0.1:38786","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-02T21:19:05.929813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.126889Z","time spent":"1.802900668s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:05.929445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.126806Z","time spent":"1.802625598s","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:19:05.929772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:04.17703Z","time spent":"1.75273777s","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-03-02T21:19:34.837675Z","caller":"traceutil/trace.go:171","msg":"trace[1819698889] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"422.784913ms","start":"2026-03-02T21:19:34.414863Z","end":"2026-03-02T21:19:34.837648Z","steps":["trace[1819698889] 'read index received' (duration: 422.594411ms)","trace[1819698889] 'applied index is now lower than readState.Index' (duration: 189.362µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:34.83808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.201809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T21:19:34.838111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.161607ms","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-02T21:19:34.83811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.821376ms","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-02T21:19:34.838297Z","caller":"traceutil/trace.go:171","msg":"trace[615062548] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"618.562908ms","start":"2026-03-02T21:19:34.219422Z","end":"2026-03-02T21:19:34.837985Z","steps":["trace[615062548] 'process raft request' (duration: 618.105252ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:34.838399Z","caller":"traceutil/trace.go:171","msg":"trace[984518708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"423.308431ms","start":"2026-03-02T21:19:34.414807Z","end":"2026-03-02T21:19:34.838116Z","steps":["trace[984518708] 'agreement among raft nodes before linearized reading' (duration: 423.22099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:34.838473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:34.414784Z","time spent":"423.661686ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T21:19:34.838531Z","caller":"traceutil/trace.go:171","msg":"trace[1265896913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"405.302338ms","start":"2026-03-02T21:19:34.432906Z","end":"2026-03-02T21:19:34.838209Z","steps":["trace[1265896913] 'agreement among raft nodes before linearized reading' (duration: 405.124506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:34.838664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:34.432896Z","time spent":"405.733444ms","remote":"127.0.0.1:39054","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-02T21:19:34.83874Z","caller":"traceutil/trace.go:171","msg":"trace[163601114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"406.006958ms","start":"2026-03-02T21:19:34.432209Z","end":"2026-03-02T21:19:34.838216Z","steps":["trace[163601114] 'agreement among raft nodes before linearized reading' (duration: 405.760275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:34.838812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:34.43219Z","time spent":"406.602118ms","remote":"127.0.0.1:39054","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-02T21:19:34.839024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:34.219352Z","time spent":"619.561503ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:19:36.385234Z","caller":"traceutil/trace.go:171","msg":"trace[909531532] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"280.111815ms","start":"2026-03-02T21:19:36.105088Z","end":"2026-03-02T21:19:36.3852Z","steps":["trace[909531532] 'process raft request' (duration: 279.910682ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:42.330523Z","caller":"traceutil/trace.go:171","msg":"trace[905705802] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"445.159859ms","start":"2026-03-02T21:19:41.885333Z","end":"2026-03-02T21:19:42.330493Z","steps":["trace[905705802] 'process raft request' (duration: 444.946066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:42.330719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:41.885301Z","time spent":"445.318693ms","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:42.471169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.740907ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T21:19:42.989507Z","caller":"traceutil/trace.go:171","msg":"trace[1503472444] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"107.210629ms","start":"2026-03-02T21:19:42.882271Z","end":"2026-03-02T21:19:42.989481Z","steps":["trace[1503472444] 'process raft request' (duration: 106.792462ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:43.764091Z","caller":"traceutil/trace.go:171","msg":"trace[1508526623] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"769.442052ms","start":"2026-03-02T21:19:42.994624Z","end":"2026-03-02T21:19:43.764066Z","steps":["trace[1508526623] 'process raft request' (duration: 769.27736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:43.764247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:42.994601Z","time spent":"769.568635ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:44.66719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.97368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:19:44.667521Z","caller":"traceutil/trace.go:171","msg":"trace[1729262712] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"1.255219709s","start":"2026-03-02T21:19:43.412277Z","end":"2026-03-02T21:19:44.667497Z","steps":["trace[1729262712] 'read index received' (duration: 351.636884ms)","trace[1729262712] 'applied index is now lower than readState.Index' (duration: 903.581535ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:44.667648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255358881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:19:44.66769Z","caller":"traceutil/trace.go:171","msg":"trace[237672697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"1.255453283s","start":"2026-03-02T21:19:43.412222Z","end":"2026-03-02T21:19:44.667675Z","steps":["trace[237672697] 'agreement among raft nodes before linearized reading' (duration: 1.255348491s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:44.667757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:43.412205Z","time spent":"1.255538655s","remote":"127.0.0.1:38778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T21:19:44.667971Z","caller":"traceutil/trace.go:171","msg":"trace[1689193508] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.672649951s","start":"2026-03-02T21:19:42.995289Z","end":"2026-03-02T21:19:44.667939Z","steps":["trace[1689193508] 'process raft request' (duration: 1.326843507s)","trace[1689193508] 'compare' (duration: 344.620884ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:44.66805Z","caller":"traceutil/trace.go:171","msg":"trace[1941466536] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.672583001s","start":"2026-03-02T21:19:42.995433Z","end":"2026-03-02T21:19:44.668016Z","steps":["trace[1941466536] 'process raft request' (duration: 1.671905659s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:44.668113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:42.995269Z","time spent":"1.672772684s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:44.668151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:42.995424Z","time spent":"1.672680572s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:46.268427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755372035675515559,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:19:46.281883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.282174912s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T21:19:46.282472Z","caller":"traceutil/trace.go:171","msg":"trace[18750916] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"514.120117ms","start":"2026-03-02T21:19:45.768314Z","end":"2026-03-02T21:19:46.282434Z","steps":["trace[18750916] 'read index received' (duration: 513.73648ms)","trace[18750916] 'applied index is now lower than readState.Index' (duration: 382.517µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:46.282619Z","caller":"traceutil/trace.go:171","msg":"trace[486040769] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.282974477s","start":"2026-03-02T21:19:44.999617Z","end":"2026-03-02T21:19:46.282592Z","steps":["trace[486040769] 'process raft request' (duration: 1.28257459s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:46.282824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:44.999602Z","time spent":"1.283085069s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:46.283939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.603363ms","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-02T21:19:46.284046Z","caller":"traceutil/trace.go:171","msg":"trace[2068095939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"515.767006ms","start":"2026-03-02T21:19:45.768255Z","end":"2026-03-02T21:19:46.284022Z","steps":["trace[2068095939] 'agreement among raft nodes before linearized reading' (duration: 514.27409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:46.284102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:45.768231Z","time spent":"515.860388ms","remote":"127.0.0.1:39054","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-02T21:19:46.928015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755372035675515561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:19:47.412914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122979339s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T21:19:47.580811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.476644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:19:47.580949Z","caller":"traceutil/trace.go:171","msg":"trace[1329330208] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.291106334s","start":"2026-03-02T21:19:46.289821Z","end":"2026-03-02T21:19:47.580927Z","steps":["trace[1329330208] 'process raft request' (duration: 1.123409206s)","trace[1329330208] 'compare' (duration: 166.783782ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:47.581184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:46.289795Z","time spent":"1.291198216s","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:19:47.581816Z","caller":"traceutil/trace.go:171","msg":"trace[125397482] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"1.154641614s","start":"2026-03-02T21:19:46.427148Z","end":"2026-03-02T21:19:47.58179Z","steps":["trace[125397482] 'read index received' (duration: 986.103751ms)","trace[125397482] 'applied index is now lower than readState.Index' (duration: 168.536463ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:47.581923Z","caller":"traceutil/trace.go:171","msg":"trace[1748178780] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"851.822591ms","start":"2026-03-02T21:19:46.730066Z","end":"2026-03-02T21:19:47.581888Z","steps":["trace[1748178780] 'process raft request' (duration: 851.587937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:47.582035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154867658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:19:47.582084Z","caller":"traceutil/trace.go:171","msg":"trace[1639035288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"1.15497972s","start":"2026-03-02T21:19:46.427085Z","end":"2026-03-02T21:19:47.582065Z","steps":["trace[1639035288] 'agreement among raft nodes before linearized reading' (duration: 1.154857587s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:47.582121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:46.427061Z","time spent":"1.155051861s","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T21:19:47.582252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:46.730021Z","time spent":"852.094226ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:19:47.582374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.867597ms","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-02T21:19:47.582462Z","caller":"traceutil/trace.go:171","msg":"trace[1416243824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"909.009989ms","start":"2026-03-02T21:19:46.673428Z","end":"2026-03-02T21:19:47.582438Z","steps":["trace[1416243824] 'agreement among raft nodes before linearized reading' (duration: 908.777565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:47.582478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.203085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T21:19:47.582507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:46.673404Z","time spent":"909.095091ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T21:19:47.582513Z","caller":"traceutil/trace.go:171","msg":"trace[2063288391] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:555; }","duration":"457.281626ms","start":"2026-03-02T21:19:47.125222Z","end":"2026-03-02T21:19:47.582504Z","steps":["trace[2063288391] 'agreement among raft nodes before linearized reading' (duration: 457.217435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:47.582549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:47.125197Z","time spent":"457.346037ms","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-03-02T21:19:47.582985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.849372ms","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-02T21:19:47.583086Z","caller":"traceutil/trace.go:171","msg":"trace[1432953555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"906.993734ms","start":"2026-03-02T21:19:46.676077Z","end":"2026-03-02T21:19:47.583071Z","steps":["trace[1432953555] 'agreement among raft nodes before linearized reading' (duration: 906.565406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:47.583177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:46.676029Z","time spent":"907.085985ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T21:19:55.524255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.686561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:19:55.524358Z","caller":"traceutil/trace.go:171","msg":"trace[1274613772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"112.829675ms","start":"2026-03-02T21:19:55.41151Z","end":"2026-03-02T21:19:55.52434Z","steps":["trace[1274613772] 'range keys from in-memory index tree' (duration: 112.531888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:06.208015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755372035675515747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T21:20:06.218326Z","caller":"traceutil/trace.go:171","msg":"trace[1988140384] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"534.819525ms","start":"2026-03-02T21:20:05.683482Z","end":"2026-03-02T21:20:06.218302Z","steps":["trace[1988140384] 'process raft request' (duration: 534.699372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:06.218446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:05.68339Z","time spent":"534.982278ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:20:06.287608Z","caller":"traceutil/trace.go:171","msg":"trace[733127950] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"579.971246ms","start":"2026-03-02T21:20:05.707618Z","end":"2026-03-02T21:20:06.287589Z","steps":["trace[733127950] 'read index received' (duration: 510.631155ms)","trace[733127950] 'applied index is now lower than readState.Index' (duration: 69.339081ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:20:06.287722Z","caller":"traceutil/trace.go:171","msg":"trace[1809148055] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"603.229984ms","start":"2026-03-02T21:20:05.684432Z","end":"2026-03-02T21:20:06.287662Z","steps":["trace[1809148055] 'process raft request' (duration: 579.080181ms)","trace[1809148055] 'compare' (duration: 23.958359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:20:06.287801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.1849ms","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-02T21:20:06.287844Z","caller":"traceutil/trace.go:171","msg":"trace[954954849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"580.280302ms","start":"2026-03-02T21:20:05.707549Z","end":"2026-03-02T21:20:06.287829Z","steps":["trace[954954849] 'agreement among raft nodes before linearized reading' (duration: 580.115588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:06.287886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:05.707524Z","time spent":"580.353174ms","remote":"127.0.0.1:39054","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-02T21:20:06.287917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:05.684412Z","time spent":"603.393677ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:20:14.686229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.151392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:20:14.686471Z","caller":"traceutil/trace.go:171","msg":"trace[1657533986] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"148.052479ms","start":"2026-03-02T21:20:14.538343Z","end":"2026-03-02T21:20:14.686395Z","steps":["trace[1657533986] 'process raft request' (duration: 31.55956ms)","trace[1657533986] 'compare' (duration: 115.864534ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:20:15.304938Z","caller":"traceutil/trace.go:171","msg":"trace[541454702] transaction","detail":"{read_only:false; number_of_response:0; response_revision:711; }","duration":"145.486822ms","start":"2026-03-02T21:20:15.159421Z","end":"2026-03-02T21:20:15.304908Z","steps":["trace[541454702] 'process raft request' (duration: 144.766015ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:26.937793Z","caller":"traceutil/trace.go:171","msg":"trace[442987842] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"478.63685ms","start":"2026-03-02T21:20:26.459131Z","end":"2026-03-02T21:20:26.937768Z","steps":["trace[442987842] 'read index received' (duration: 478.424155ms)","trace[442987842] 'applied index is now lower than readState.Index' (duration: 211.895µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:20:26.937923Z","caller":"traceutil/trace.go:171","msg":"trace[1821224247] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"561.180539ms","start":"2026-03-02T21:20:26.376733Z","end":"2026-03-02T21:20:26.937914Z","steps":["trace[1821224247] 'process raft request' (duration: 560.892012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:26.938037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:26.376709Z","time spent":"561.23885ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:20:26.93809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.449326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:20:26.938201Z","caller":"traceutil/trace.go:171","msg":"trace[1228212508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"476.561418ms","start":"2026-03-02T21:20:26.461598Z","end":"2026-03-02T21:20:26.938159Z","steps":["trace[1228212508] 'agreement among raft nodes before linearized reading' (duration: 476.421935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:26.938248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:26.461593Z","time spent":"476.64675ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T21:20:26.93841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.865925ms","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-02T21:20:26.938439Z","caller":"traceutil/trace.go:171","msg":"trace[2069310815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"190.944828ms","start":"2026-03-02T21:20:26.747485Z","end":"2026-03-02T21:20:26.93843Z","steps":["trace[2069310815] 'agreement among raft nodes before linearized reading' (duration: 190.827635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:26.938549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.203454ms","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-02T21:20:26.938581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.448191ms","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-02T21:20:26.938581Z","caller":"traceutil/trace.go:171","msg":"trace[1527320342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"417.259895ms","start":"2026-03-02T21:20:26.521311Z","end":"2026-03-02T21:20:26.938571Z","steps":["trace[1527320342] 'agreement among raft nodes before linearized reading' (duration: 417.131482ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:26.938601Z","caller":"traceutil/trace.go:171","msg":"trace[1882477009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"479.496382ms","start":"2026-03-02T21:20:26.459099Z","end":"2026-03-02T21:20:26.938595Z","steps":["trace[1882477009] 'agreement among raft nodes before linearized reading' (duration: 479.442461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:26.938611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:26.521302Z","time spent":"417.303896ms","remote":"127.0.0.1:39054","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-02T21:20:26.938622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:26.459086Z","time spent":"479.531593ms","remote":"127.0.0.1:39054","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-02T21:20:27.047268Z","caller":"traceutil/trace.go:171","msg":"trace[1118753754] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"102.977058ms","start":"2026-03-02T21:20:26.944261Z","end":"2026-03-02T21:20:27.047239Z","steps":["trace[1118753754] 'process raft request' (duration: 40.425527ms)","trace[1118753754] 'compare' (duration: 62.406197ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:20:27.047879Z","caller":"traceutil/trace.go:171","msg":"trace[2118753520] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"103.029429ms","start":"2026-03-02T21:20:26.944819Z","end":"2026-03-02T21:20:27.047848Z","steps":["trace[2118753520] 'process raft request' (duration: 102.978168ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:27.048045Z","caller":"traceutil/trace.go:171","msg":"trace[303217235] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"103.221174ms","start":"2026-03-02T21:20:26.94478Z","end":"2026-03-02T21:20:27.048001Z","steps":["trace[303217235] 'process raft request' (duration: 102.870935ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:49.512239Z","caller":"traceutil/trace.go:171","msg":"trace[1221385247] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"185.527924ms","start":"2026-03-02T21:20:49.326675Z","end":"2026-03-02T21:20:49.512203Z","steps":["trace[1221385247] 'process raft request' (duration: 185.331779ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:49.515685Z","caller":"traceutil/trace.go:171","msg":"trace[227675200] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"188.6508ms","start":"2026-03-02T21:20:49.327005Z","end":"2026-03-02T21:20:49.515656Z","steps":["trace[227675200] 'process raft request' (duration: 188.502126ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:20:51.67512Z","caller":"traceutil/trace.go:171","msg":"trace[1192386083] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"154.79823ms","start":"2026-03-02T21:20:51.520299Z","end":"2026-03-02T21:20:51.675098Z","steps":["trace[1192386083] 'read index received' (duration: 154.645795ms)","trace[1192386083] 'applied index is now lower than readState.Index' (duration: 151.795µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:20:51.675166Z","caller":"traceutil/trace.go:171","msg":"trace[1025898017] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"155.640794ms","start":"2026-03-02T21:20:51.519496Z","end":"2026-03-02T21:20:51.675136Z","steps":["trace[1025898017] 'process raft request' (duration: 155.49326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:51.675292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.975355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-02T21:20:51.675322Z","caller":"traceutil/trace.go:171","msg":"trace[2022409729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"155.024477ms","start":"2026-03-02T21:20:51.520289Z","end":"2026-03-02T21:20:51.675313Z","steps":["trace[2022409729] 'agreement among raft nodes before linearized reading' (duration: 154.891963ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:00.206366Z","caller":"traceutil/trace.go:171","msg":"trace[1767313120] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"127.71083ms","start":"2026-03-02T21:21:00.078625Z","end":"2026-03-02T21:21:00.206336Z","steps":["trace[1767313120] 'read index received' (duration: 127.461143ms)","trace[1767313120] 'applied index is now lower than readState.Index' (duration: 248.727µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:00.206493Z","caller":"traceutil/trace.go:171","msg":"trace[396438608] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"262.296292ms","start":"2026-03-02T21:20:59.944156Z","end":"2026-03-02T21:21:00.206452Z","steps":["trace[396438608] 'process raft request' (duration: 261.980623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:00.206788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.09337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T21:21:00.206919Z","caller":"traceutil/trace.go:171","msg":"trace[75742631] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:902; }","duration":"128.337958ms","start":"2026-03-02T21:21:00.078563Z","end":"2026-03-02T21:21:00.206901Z","steps":["trace[75742631] 'agreement among raft nodes before linearized reading' (duration: 127.928156ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:06.59499Z","caller":"traceutil/trace.go:171","msg":"trace[33231229] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"120.6031ms","start":"2026-03-02T21:21:06.474353Z","end":"2026-03-02T21:21:06.594956Z","steps":["trace[33231229] 'read index received' (duration: 28.659884ms)","trace[33231229] 'applied index is now lower than readState.Index' (duration: 91.941866ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:06.595127Z","caller":"traceutil/trace.go:171","msg":"trace[613482079] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"121.247318ms","start":"2026-03-02T21:21:06.473819Z","end":"2026-03-02T21:21:06.595066Z","steps":["trace[613482079] 'process raft request' (duration: 29.265611ms)","trace[613482079] 'compare' (duration: 91.74249ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:06.595225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.855967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-03-02T21:21:06.59528Z","caller":"traceutil/trace.go:171","msg":"trace[1632566948] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:922; }","duration":"120.95048ms","start":"2026-03-02T21:21:06.474315Z","end":"2026-03-02T21:21:06.595266Z","steps":["trace[1632566948] 'agreement among raft nodes before linearized reading' (duration: 120.771355ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:07.59057Z","caller":"traceutil/trace.go:171","msg":"trace[26058408] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"117.556039ms","start":"2026-03-02T21:21:07.472989Z","end":"2026-03-02T21:21:07.590545Z","steps":["trace[26058408] 'read index received' (duration: 117.370004ms)","trace[26058408] 'applied index is now lower than readState.Index' (duration: 184.725µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:07.590843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.833447ms","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-02T21:21:07.590889Z","caller":"traceutil/trace.go:171","msg":"trace[1940220191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"117.947111ms","start":"2026-03-02T21:21:07.472929Z","end":"2026-03-02T21:21:07.590876Z","steps":["trace[1940220191] 'agreement among raft nodes before linearized reading' (duration: 117.714754ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:07.590983Z","caller":"traceutil/trace.go:171","msg":"trace[724808795] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"173.716967ms","start":"2026-03-02T21:21:07.417135Z","end":"2026-03-02T21:21:07.590852Z","steps":["trace[724808795] 'process raft request' (duration: 173.252913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:08.183493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.31681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:21:08.183583Z","caller":"traceutil/trace.go:171","msg":"trace[1526477640] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:933; }","duration":"291.490496ms","start":"2026-03-02T21:21:07.89207Z","end":"2026-03-02T21:21:08.183561Z","steps":["trace[1526477640] 'count revisions from in-memory index tree' (duration: 291.228518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:08.183755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.205021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-02T21:21:08.183807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.908798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-02T21:21:08.183807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.006724ms","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-02T21:21:08.183889Z","caller":"traceutil/trace.go:171","msg":"trace[1816269071] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:933; }","duration":"103.081524ms","start":"2026-03-02T21:21:08.080785Z","end":"2026-03-02T21:21:08.183867Z","steps":["trace[1816269071] 'count revisions from in-memory index tree' (duration: 102.851947ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:08.183932Z","caller":"traceutil/trace.go:171","msg":"trace[2047554396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"239.215091ms","start":"2026-03-02T21:21:07.94469Z","end":"2026-03-02T21:21:08.183906Z","steps":["trace[2047554396] 'range keys from in-memory index tree' (duration: 238.727216ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:08.183863Z","caller":"traceutil/trace.go:171","msg":"trace[89776119] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:933; }","duration":"113.442307ms","start":"2026-03-02T21:21:08.070396Z","end":"2026-03-02T21:21:08.183839Z","steps":["trace[89776119] 'count revisions from in-memory index tree' (duration: 113.075247ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:10.509491Z","caller":"traceutil/trace.go:171","msg":"trace[2042995281] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"228.964425ms","start":"2026-03-02T21:21:10.2805Z","end":"2026-03-02T21:21:10.509465Z","steps":["trace[2042995281] 'process raft request' (duration: 228.742689ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:16.780323Z","caller":"traceutil/trace.go:171","msg":"trace[323254142] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"168.835413ms","start":"2026-03-02T21:21:16.611463Z","end":"2026-03-02T21:21:16.780298Z","steps":["trace[323254142] 'read index received' (duration: 168.648838ms)","trace[323254142] 'applied index is now lower than readState.Index' (duration: 185.755µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:16.780471Z","caller":"traceutil/trace.go:171","msg":"trace[669696088] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"170.069969ms","start":"2026-03-02T21:21:16.610362Z","end":"2026-03-02T21:21:16.780432Z","steps":["trace[669696088] 'process raft request' (duration: 169.825942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:16.780525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.042929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-03-02T21:21:16.780561Z","caller":"traceutil/trace.go:171","msg":"trace[929039013] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:977; }","duration":"169.117362ms","start":"2026-03-02T21:21:16.611434Z","end":"2026-03-02T21:21:16.780551Z","steps":["trace[929039013] 'agreement among raft nodes before linearized reading' (duration: 168.960087ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:17.486695Z","caller":"traceutil/trace.go:171","msg":"trace[1040434910] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"184.273524ms","start":"2026-03-02T21:21:17.302391Z","end":"2026-03-02T21:21:17.486664Z","steps":["trace[1040434910] 'process raft request' (duration: 132.380357ms)","trace[1040434910] 'compare' (duration: 51.705901ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:21.502258Z","caller":"traceutil/trace.go:171","msg":"trace[1280785434] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"106.235791ms","start":"2026-03-02T21:21:21.395968Z","end":"2026-03-02T21:21:21.502204Z","steps":["trace[1280785434] 'process raft request' (duration: 105.157029ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:27.824399Z","caller":"traceutil/trace.go:171","msg":"trace[1933741366] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"167.528185ms","start":"2026-03-02T21:21:27.656854Z","end":"2026-03-02T21:21:27.824382Z","steps":["trace[1933741366] 'process raft request' (duration: 167.414522ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:28.817736Z","caller":"traceutil/trace.go:171","msg":"trace[1725562219] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"359.364018ms","start":"2026-03-02T21:21:28.458349Z","end":"2026-03-02T21:21:28.817713Z","steps":["trace[1725562219] 'read index received' (duration: 359.176382ms)","trace[1725562219] 'applied index is now lower than readState.Index' (duration: 186.806µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:28.817892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.519402ms","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-02T21:21:28.817932Z","caller":"traceutil/trace.go:171","msg":"trace[844119769] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1016; }","duration":"359.612585ms","start":"2026-03-02T21:21:28.458309Z","end":"2026-03-02T21:21:28.817922Z","steps":["trace[844119769] 'agreement among raft nodes before linearized reading' (duration: 359.497482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:28.818068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:28.458292Z","time spent":"359.671938ms","remote":"127.0.0.1:39122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-02T21:21:28.81807Z","caller":"traceutil/trace.go:171","msg":"trace[1984926224] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"377.386677ms","start":"2026-03-02T21:21:28.440646Z","end":"2026-03-02T21:21:28.818033Z","steps":["trace[1984926224] 'process raft request' (duration: 376.946693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:28.8183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:28.440627Z","time spent":"377.590572ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:21:29.103269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.061413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:21:29.103435Z","caller":"traceutil/trace.go:171","msg":"trace[1647481374] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"234.307129ms","start":"2026-03-02T21:21:28.86907Z","end":"2026-03-02T21:21:29.103377Z","steps":["trace[1647481374] 'process raft request' (duration: 21.011749ms)","trace[1647481374] 'compare' (duration: 212.769784ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:30.164503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.866298ms","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-02T21:21:30.164583Z","caller":"traceutil/trace.go:171","msg":"trace[1026888825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"335.971921ms","start":"2026-03-02T21:21:29.828594Z","end":"2026-03-02T21:21:30.164566Z","steps":["trace[1026888825] 'range keys from in-memory index tree' (duration: 335.746033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:30.164622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:29.828578Z","time spent":"336.035933ms","remote":"127.0.0.1:39054","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-02T21:21:30.16468Z","caller":"traceutil/trace.go:171","msg":"trace[263473094] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"332.183876ms","start":"2026-03-02T21:21:29.832464Z","end":"2026-03-02T21:21:30.164648Z","steps":["trace[263473094] 'process raft request' (duration: 234.33065ms)","trace[263473094] 'compare' (duration: 97.34975ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:30.164486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.811676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T21:21:30.164757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:29.832452Z","time spent":"332.257267ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:21:30.164764Z","caller":"traceutil/trace.go:171","msg":"trace[246089027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"753.128125ms","start":"2026-03-02T21:21:29.411603Z","end":"2026-03-02T21:21:30.164731Z","steps":["trace[246089027] 'range keys from in-memory index tree' (duration: 752.716363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:30.164891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:29.41158Z","time spent":"753.26996ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T21:21:30.164625Z","caller":"traceutil/trace.go:171","msg":"trace[924898218] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"248.807966ms","start":"2026-03-02T21:21:29.9158Z","end":"2026-03-02T21:21:30.164608Z","steps":["trace[924898218] 'read index received' (duration: 150.91878ms)","trace[924898218] 'applied index is now lower than readState.Index' (duration: 97.888096ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:30.165293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.415455ms","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-02T21:21:30.165428Z","caller":"traceutil/trace.go:171","msg":"trace[386080056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"249.617921ms","start":"2026-03-02T21:21:29.91577Z","end":"2026-03-02T21:21:30.165388Z","steps":["trace[386080056] 'agreement among raft nodes before linearized reading' (duration: 249.135016ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:30.348198Z","caller":"traceutil/trace.go:171","msg":"trace[1752217823] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"177.664021ms","start":"2026-03-02T21:21:30.170506Z","end":"2026-03-02T21:21:30.34817Z","steps":["trace[1752217823] 'process raft request' (duration: 177.608419ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:30.348416Z","caller":"traceutil/trace.go:171","msg":"trace[827643278] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"177.934299ms","start":"2026-03-02T21:21:30.170471Z","end":"2026-03-02T21:21:30.348405Z","steps":["trace[827643278] 'process raft request' (duration: 177.513026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:50.855465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.795412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-03-02T21:21:50.855609Z","caller":"traceutil/trace.go:171","msg":"trace[1186147911] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1082; }","duration":"185.976288ms","start":"2026-03-02T21:21:50.669602Z","end":"2026-03-02T21:21:50.855578Z","steps":["trace[1186147911] 'range keys from in-memory index tree' (duration: 185.638047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:50.855585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.16052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-03-02T21:21:50.855684Z","caller":"traceutil/trace.go:171","msg":"trace[2136214631] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1082; }","duration":"183.298143ms","start":"2026-03-02T21:21:50.672365Z","end":"2026-03-02T21:21:50.855663Z","steps":["trace[2136214631] 'range keys from in-memory index tree' (duration: 183.018664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:53.824718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.055968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:85 size:63188"} {"level":"info","ts":"2026-03-02T21:21:53.824812Z","caller":"traceutil/trace.go:171","msg":"trace[1445901144] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:85; response_revision:1090; }","duration":"137.204993ms","start":"2026-03-02T21:21:53.68759Z","end":"2026-03-02T21:21:53.824795Z","steps":["trace[1445901144] 'range keys from in-memory index tree' (duration: 136.625096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:59.995973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.144347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8xv2w\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-02T21:21:59.996084Z","caller":"traceutil/trace.go:171","msg":"trace[626198777] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8xv2w; range_end:; response_count:1; response_revision:1106; }","duration":"130.296382ms","start":"2026-03-02T21:21:59.865762Z","end":"2026-03-02T21:21:59.996059Z","steps":["trace[626198777] 'range keys from in-memory index tree' (duration: 129.975021ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:00.943188Z","caller":"traceutil/trace.go:171","msg":"trace[1238210425] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"110.964103ms","start":"2026-03-02T21:22:00.832195Z","end":"2026-03-02T21:22:00.94316Z","steps":["trace[1238210425] 'read index received' (duration: 108.106413ms)","trace[1238210425] 'applied index is now lower than readState.Index' (duration: 2.85636ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:00.943271Z","caller":"traceutil/trace.go:171","msg":"trace[2142885048] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"149.647288ms","start":"2026-03-02T21:22:00.793586Z","end":"2026-03-02T21:22:00.943234Z","steps":["trace[2142885048] 'process raft request' (duration: 146.740876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:00.943443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.19455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8xv2w\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-02T21:22:00.943497Z","caller":"traceutil/trace.go:171","msg":"trace[1344055182] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8xv2w; range_end:; response_count:1; response_revision:1109; }","duration":"111.328735ms","start":"2026-03-02T21:22:00.832158Z","end":"2026-03-02T21:22:00.943486Z","steps":["trace[1344055182] 'agreement among raft nodes before linearized reading' (duration: 111.099767ms)"],"step_count":1}