{"level":"warn","ts":"2026-08-16T12:53:12.141842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:12.141929Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-08-16T12:53:12.141992Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:12.142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-16T12:53:12.142014Z","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-08-16T12:53:12.142632Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-08-16T12:53:12.142702Z","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.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-08-16T12:53:12.147748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.844579ms"} {"level":"info","ts":"2026-08-16T12:53:12.158204Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-08-16T12:53:12.158284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:53:12.158311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-08-16T12:53:12.158322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:53:12.158333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-08-16T12:53:12.158369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-08-16T12:53:12.170283Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:53:12.171579Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:53:12.172568Z","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-08-16T12:53:12.17459Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:53:12.17548Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:53:12.177625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-08-16T12:53:12.17776Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-16T12:53:12.179632Z","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-08-16T12:53:12.179739Z","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-08-16T12:53:12.179751Z","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-08-16T12:53:12.180397Z","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-08-16T12:53:12.180619Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:53:12.180645Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:53:12.181855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-16T12:53:12.181872Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-16T12:53:12.461218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:53:12.461283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:53:12.461332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-08-16T12:53:12.461359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:53:12.461366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-16T12:53:12.461374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-08-16T12:53:12.461385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-16T12:53:12.466843Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:53:12.466969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:12.467234Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:53:12.467344Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:53:12.467532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:12.466899Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:12.470515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-08-16T12:53:12.472381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:53:12.472531Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:12.472699Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:12.472728Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:14.121983Z","caller":"traceutil/trace.go:171","msg":"trace[1949388189] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"115.176304ms","start":"2026-08-16T12:53:14.006772Z","end":"2026-08-16T12:53:14.121948Z","steps":["trace[1949388189] 'process raft request' (duration: 105.995138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:14.122571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.713175ms","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-08-16T12:53:14.12271Z","caller":"traceutil/trace.go:171","msg":"trace[1570862423] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:16; }","duration":"147.92891ms","start":"2026-08-16T12:53:13.974758Z","end":"2026-08-16T12:53:14.122687Z","steps":["trace[1570862423] 'agreement among raft nodes before linearized reading' (duration: 147.349647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:14.122999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.836892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-08-16T12:53:14.123086Z","caller":"traceutil/trace.go:171","msg":"trace[2138479104] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:16; }","duration":"116.973795ms","start":"2026-08-16T12:53:14.006094Z","end":"2026-08-16T12:53:14.123067Z","steps":["trace[2138479104] 'agreement among raft nodes before linearized reading' (duration: 115.991753ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:22.441453Z","caller":"traceutil/trace.go:171","msg":"trace[1056308522] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"410.747066ms","start":"2026-08-16T12:53:22.030675Z","end":"2026-08-16T12:53:22.441422Z","steps":["trace[1056308522] 'read index received' (duration: 410.609603ms)","trace[1056308522] 'applied index is now lower than readState.Index' (duration: 136.173µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:22.441542Z","caller":"traceutil/trace.go:171","msg":"trace[1361317940] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"417.768004ms","start":"2026-08-16T12:53:22.02374Z","end":"2026-08-16T12:53:22.441508Z","steps":["trace[1361317940] 'process raft request' (duration: 417.527478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.93672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-16T12:53:22.442155Z","caller":"traceutil/trace.go:171","msg":"trace[1266908896] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"411.502794ms","start":"2026-08-16T12:53:22.030636Z","end":"2026-08-16T12:53:22.442139Z","steps":["trace[1266908896] 'agreement among raft nodes before linearized reading' (duration: 410.92928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.442187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:22.030623Z","time spent":"411.556744ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-08-16T12:53:22.441693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.811159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-16T12:53:22.44227Z","caller":"traceutil/trace.go:171","msg":"trace[743611365] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:339; }","duration":"260.386163ms","start":"2026-08-16T12:53:22.181853Z","end":"2026-08-16T12:53:22.442239Z","steps":["trace[743611365] 'agreement among raft nodes before linearized reading' (duration: 259.818849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.567961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-08-16T12:53:22.442329Z","caller":"traceutil/trace.go:171","msg":"trace[1434404087] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:339; }","duration":"210.183496ms","start":"2026-08-16T12:53:22.232128Z","end":"2026-08-16T12:53:22.442312Z","steps":["trace[1434404087] 'agreement among raft nodes before linearized reading' (duration: 209.574062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.531098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-16T12:53:22.442447Z","caller":"traceutil/trace.go:171","msg":"trace[1103023719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"110.241344ms","start":"2026-08-16T12:53:22.332186Z","end":"2026-08-16T12:53:22.442427Z","steps":["trace[1103023719] 'agreement among raft nodes before linearized reading' (duration: 109.549698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.44173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.220832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-08-16T12:53:22.442526Z","caller":"traceutil/trace.go:171","msg":"trace[120258749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:339; }","duration":"361.050542ms","start":"2026-08-16T12:53:22.081459Z","end":"2026-08-16T12:53:22.44251Z","steps":["trace[120258749] 'agreement among raft nodes before linearized reading' (duration: 360.168831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.844515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-16T12:53:22.442574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:22.081443Z","time spent":"361.117923ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-08-16T12:53:22.441799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.575253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:53:22.442632Z","caller":"traceutil/trace.go:171","msg":"trace[1470068454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"276.460333ms","start":"2026-08-16T12:53:22.16616Z","end":"2026-08-16T12:53:22.44262Z","steps":["trace[1470068454] 'agreement among raft nodes before linearized reading' (duration: 275.559842ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:22.442618Z","caller":"traceutil/trace.go:171","msg":"trace[470618593] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"311.677705ms","start":"2026-08-16T12:53:22.13092Z","end":"2026-08-16T12:53:22.442598Z","steps":["trace[470618593] 'agreement among raft nodes before linearized reading' (duration: 310.848465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.503408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-16T12:53:22.442707Z","caller":"traceutil/trace.go:171","msg":"trace[1812592732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"160.40771ms","start":"2026-08-16T12:53:22.282291Z","end":"2026-08-16T12:53:22.442698Z","steps":["trace[1812592732] 'agreement among raft nodes before linearized reading' (duration: 159.508209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.441864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.762014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-16T12:53:22.442795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:22.130909Z","time spent":"311.879449ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-08-16T12:53:22.442854Z","caller":"traceutil/trace.go:171","msg":"trace[438819806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:339; }","duration":"205.729837ms","start":"2026-08-16T12:53:22.237077Z","end":"2026-08-16T12:53:22.442807Z","steps":["trace[438819806] 'agreement among raft nodes before linearized reading' (duration: 204.566541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:22.44353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:22.023708Z","time spent":"418.272365ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:27.991323Z","caller":"traceutil/trace.go:171","msg":"trace[1131897070] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"295.067692ms","start":"2026-08-16T12:53:27.696228Z","end":"2026-08-16T12:53:27.991296Z","steps":["trace[1131897070] 'read index received' (duration: 294.876678ms)","trace[1131897070] 'applied index is now lower than readState.Index' (duration: 189.784µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:27.991592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.343548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:27.991657Z","caller":"traceutil/trace.go:171","msg":"trace[311863353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"295.454772ms","start":"2026-08-16T12:53:27.696189Z","end":"2026-08-16T12:53:27.991643Z","steps":["trace[311863353] 'agreement among raft nodes before linearized reading' (duration: 295.318668ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:27.991681Z","caller":"traceutil/trace.go:171","msg":"trace[1626325363] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"301.20756ms","start":"2026-08-16T12:53:27.690445Z","end":"2026-08-16T12:53:27.991653Z","steps":["trace[1626325363] 'process raft request' (duration: 300.708498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:27.991815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:27.690416Z","time spent":"301.333293ms","remote":"127.0.0.1:49096","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-08-16T12:53:28.493263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193144707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:29.056572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.977518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:29.056672Z","caller":"traceutil/trace.go:171","msg":"trace[211111579] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"1.063884995s","start":"2026-08-16T12:53:27.992772Z","end":"2026-08-16T12:53:29.056657Z","steps":["trace[211111579] 'read index received' (duration: 546.550349ms)","trace[211111579] 'applied index is now lower than readState.Index' (duration: 517.333196ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:29.056737Z","caller":"traceutil/trace.go:171","msg":"trace[451312407] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.198850984s","start":"2026-08-16T12:53:27.857838Z","end":"2026-08-16T12:53:29.056689Z","steps":["trace[451312407] 'process raft request' (duration: 681.540079ms)","trace[451312407] 'compare' (duration: 516.67225ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:29.05679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064023178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:29.056819Z","caller":"traceutil/trace.go:171","msg":"trace[522352059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"1.064088719s","start":"2026-08-16T12:53:27.992722Z","end":"2026-08-16T12:53:29.05681Z","steps":["trace[522352059] 'agreement among raft nodes before linearized reading' (duration: 1.064032898s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:29.056858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:27.992704Z","time spent":"1.064147151s","remote":"127.0.0.1:48840","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-08-16T12:53:29.056898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:27.857812Z","time spent":"1.198998938s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:29.057076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.937725ms","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-08-16T12:53:29.057112Z","caller":"traceutil/trace.go:171","msg":"trace[529308256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"554.018427ms","start":"2026-08-16T12:53:28.503085Z","end":"2026-08-16T12:53:29.057104Z","steps":["trace[529308256] 'agreement among raft nodes before linearized reading' (duration: 553.949875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:29.057139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:28.503059Z","time spent":"554.074588ms","remote":"127.0.0.1:49096","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-08-16T12:53:29.057306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.326123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:53:29.05734Z","caller":"traceutil/trace.go:171","msg":"trace[2091473824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"520.401195ms","start":"2026-08-16T12:53:28.53693Z","end":"2026-08-16T12:53:29.057331Z","steps":["trace[2091473824] 'agreement among raft nodes before linearized reading' (duration: 520.349543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:29.057366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:28.536899Z","time spent":"520.460896ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:53:29.05737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.017094ms","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-08-16T12:53:29.057411Z","caller":"traceutil/trace.go:171","msg":"trace[759801503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"532.078015ms","start":"2026-08-16T12:53:28.525321Z","end":"2026-08-16T12:53:29.057399Z","steps":["trace[759801503] 'agreement among raft nodes before linearized reading' (duration: 531.992663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:29.057455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:28.525311Z","time spent":"532.137757ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:53:29.56524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193144709,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:53:29.655192Z","caller":"traceutil/trace.go:171","msg":"trace[884076216] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"592.685341ms","start":"2026-08-16T12:53:29.062477Z","end":"2026-08-16T12:53:29.655163Z","steps":["trace[884076216] 'process raft request' (duration: 581.158113ms)","trace[884076216] 'compare' (duration: 10.984986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:29.656239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:29.062454Z","time spent":"592.812234ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:30.133427Z","caller":"traceutil/trace.go:171","msg":"trace[618690557] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:421; }","duration":"1.068196021s","start":"2026-08-16T12:53:29.065176Z","end":"2026-08-16T12:53:30.133372Z","steps":["trace[618690557] 'read index received' (duration: 578.473653ms)","trace[618690557] 'applied index is now lower than readState.Index' (duration: 489.721578ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:30.133727Z","caller":"traceutil/trace.go:171","msg":"trace[1436949507] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.07036022s","start":"2026-08-16T12:53:29.063309Z","end":"2026-08-16T12:53:30.133669Z","steps":["trace[1436949507] 'process raft request' (duration: 1.070027222s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:30.133915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:29.063302Z","time spent":"1.070518783s","remote":"127.0.0.1:49096","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-08-16T12:53:30.133923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.139152ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:30.134008Z","caller":"traceutil/trace.go:171","msg":"trace[703101249] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:409; }","duration":"758.245385ms","start":"2026-08-16T12:53:29.37575Z","end":"2026-08-16T12:53:30.133995Z","steps":["trace[703101249] 'agreement among raft nodes before linearized reading' (duration: 758.122532ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:30.133784Z","caller":"traceutil/trace.go:171","msg":"trace[142216573] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.070530484s","start":"2026-08-16T12:53:29.063097Z","end":"2026-08-16T12:53:30.133627Z","steps":["trace[142216573] 'process raft request' (duration: 1.069952501s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:30.134159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:29.06308Z","time spent":"1.070998314s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:30.134302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.56714ms","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-08-16T12:53:30.134323Z","caller":"traceutil/trace.go:171","msg":"trace[1700741801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"138.632801ms","start":"2026-08-16T12:53:29.995684Z","end":"2026-08-16T12:53:30.134316Z","steps":["trace[1700741801] 'agreement among raft nodes before linearized reading' (duration: 138.58598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:30.134409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069232484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:30.134455Z","caller":"traceutil/trace.go:171","msg":"trace[40873112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"1.069293626s","start":"2026-08-16T12:53:29.065148Z","end":"2026-08-16T12:53:30.134441Z","steps":["trace[40873112] 'agreement among raft nodes before linearized reading' (duration: 1.069201054s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:30.134478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:29.06514Z","time spent":"1.069333476s","remote":"127.0.0.1:48854","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-08-16T12:53:55.920822Z","caller":"traceutil/trace.go:171","msg":"trace[2127205242] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"150.042207ms","start":"2026-08-16T12:53:55.770757Z","end":"2026-08-16T12:53:55.9208Z","steps":["trace[2127205242] 'read index received' (duration: 149.822212ms)","trace[2127205242] 'applied index is now lower than readState.Index' (duration: 166.444µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:55.921047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.271252ms","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-08-16T12:53:55.921089Z","caller":"traceutil/trace.go:171","msg":"trace[1429175716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"150.353424ms","start":"2026-08-16T12:53:55.770725Z","end":"2026-08-16T12:53:55.921078Z","steps":["trace[1429175716] 'agreement among raft nodes before linearized reading' (duration: 150.18762ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:56.054587Z","caller":"traceutil/trace.go:171","msg":"trace[642074894] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"129.451946ms","start":"2026-08-16T12:53:55.925117Z","end":"2026-08-16T12:53:56.054569Z","steps":["trace[642074894] 'process raft request' (duration: 129.412576ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:56.05474Z","caller":"traceutil/trace.go:171","msg":"trace[1970463690] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"132.71836ms","start":"2026-08-16T12:53:55.922Z","end":"2026-08-16T12:53:56.054719Z","steps":["trace[1970463690] 'process raft request' (duration: 36.291243ms)","trace[1970463690] 'compare' (duration: 96.14066ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:02.209829Z","caller":"traceutil/trace.go:171","msg":"trace[1904182348] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"115.526075ms","start":"2026-08-16T12:54:02.094104Z","end":"2026-08-16T12:54:02.20963Z","steps":["trace[1904182348] 'process raft request' (duration: 115.348351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.197051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145178,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:04.69781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145178,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:04.703487Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073265175s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:54:04.704025Z","caller":"traceutil/trace.go:171","msg":"trace[1587381451] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"1.007711978s","start":"2026-08-16T12:54:03.696289Z","end":"2026-08-16T12:54:04.704001Z","steps":["trace[1587381451] 'read index received' (duration: 1.007483613s)","trace[1587381451] 'applied index is now lower than readState.Index' (duration: 227.355µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:04.704129Z","caller":"traceutil/trace.go:171","msg":"trace[440381787] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.074067823s","start":"2026-08-16T12:54:03.630052Z","end":"2026-08-16T12:54:04.70412Z","steps":["trace[440381787] 'process raft request' (duration: 1.073736596s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.704231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:03.630033Z","time spent":"1.074129365s","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:54:04.704363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008073036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:04.704448Z","caller":"traceutil/trace.go:171","msg":"trace[1698967484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"1.008192339s","start":"2026-08-16T12:54:03.696244Z","end":"2026-08-16T12:54:04.704436Z","steps":["trace[1698967484] 'agreement among raft nodes before linearized reading' (duration: 1.008094597s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.70448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:03.696223Z","time spent":"1.00824997s","remote":"127.0.0.1:48840","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-08-16T12:54:04.704475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.537739ms","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-08-16T12:54:04.7045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.981976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:54:04.704514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.038162ms","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-08-16T12:54:04.704537Z","caller":"traceutil/trace.go:171","msg":"trace[438409729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"348.675313ms","start":"2026-08-16T12:54:04.355847Z","end":"2026-08-16T12:54:04.704522Z","steps":["trace[438409729] 'agreement among raft nodes before linearized reading' (duration: 348.427358ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:04.704557Z","caller":"traceutil/trace.go:171","msg":"trace[1937002222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"390.060888ms","start":"2026-08-16T12:54:04.314467Z","end":"2026-08-16T12:54:04.704528Z","steps":["trace[1937002222] 'agreement among raft nodes before linearized reading' (duration: 389.867514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.704578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.355834Z","time spent":"348.738594ms","remote":"127.0.0.1:49096","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-08-16T12:54:04.704581Z","caller":"traceutil/trace.go:171","msg":"trace[1753518402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"354.161745ms","start":"2026-08-16T12:54:04.3504Z","end":"2026-08-16T12:54:04.704562Z","steps":["trace[1753518402] 'agreement among raft nodes before linearized reading' (duration: 353.915479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.704628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.314443Z","time spent":"390.144609ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:54:04.70463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.35038Z","time spent":"354.240997ms","remote":"127.0.0.1:49096","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-08-16T12:54:04.704738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.921784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:54:04.704858Z","caller":"traceutil/trace.go:171","msg":"trace[184538916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"491.079918ms","start":"2026-08-16T12:54:04.213762Z","end":"2026-08-16T12:54:04.704842Z","steps":["trace[184538916] 'agreement among raft nodes before linearized reading' (duration: 490.466515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:04.704969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.213743Z","time spent":"491.213961ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:54:05.211679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:05.712496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:05.85607Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.147477896s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-16T12:54:06.711417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000811119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-16T12:54:06.711497Z","caller":"traceutil/trace.go:171","msg":"trace[1180739738] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000924741s","start":"2026-08-16T12:54:04.710555Z","end":"2026-08-16T12:54:06.71148Z","steps":["trace[1180739738] 'agreement among raft nodes before linearized reading' (duration: 2.000825429s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:06.711552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.710541Z","time spent":"2.000995203s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/16 12:54:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-16T12:54:06.904603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048241355s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:54:06.904707Z","caller":"traceutil/trace.go:171","msg":"trace[1908870423] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.19616854s","start":"2026-08-16T12:54:04.708519Z","end":"2026-08-16T12:54:06.904687Z","steps":["trace[1908870423] 'process raft request' (duration: 1.147745761s)","trace[1908870423] 'compare' (duration: 1.048125542s)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:06.904763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.708497Z","time spent":"2.196239332s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:54:06.960292Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104018383s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:54:06.960832Z","caller":"traceutil/trace.go:171","msg":"trace[706811556] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"2.252200044s","start":"2026-08-16T12:54:04.708579Z","end":"2026-08-16T12:54:06.960779Z","steps":["trace[706811556] 'process raft request' (duration: 2.251865917s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:06.960931Z","caller":"traceutil/trace.go:171","msg":"trace[2061522076] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"2.25157048s","start":"2026-08-16T12:54:04.70932Z","end":"2026-08-16T12:54:06.960891Z","steps":["trace[2061522076] 'process raft request' (duration: 2.251228572s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:06.961008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.708552Z","time spent":"2.252361588s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:06.961088Z","caller":"traceutil/trace.go:171","msg":"trace[2068040550] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"2.251329974s","start":"2026-08-16T12:54:04.709747Z","end":"2026-08-16T12:54:06.961077Z","steps":["trace[2068040550] 'process raft request' (duration: 2.251080938s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:06.961194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.70974Z","time spent":"2.251379525s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:06.961346Z","caller":"traceutil/trace.go:171","msg":"trace[544578094] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:678; }","duration":"2.250720231s","start":"2026-08-16T12:54:04.710583Z","end":"2026-08-16T12:54:06.961303Z","steps":["trace[544578094] 'read index received' (duration: 1.145677705s)","trace[544578094] 'applied index is now lower than readState.Index' (duration: 1.105041256s)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:06.961331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:04.709312Z","time spent":"2.251678512s","remote":"127.0.0.1:49096","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-08-16T12:54:06.961531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.74687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:06.961478Z","caller":"traceutil/trace.go:171","msg":"trace[1800503437] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.574622332s","start":"2026-08-16T12:54:05.386714Z","end":"2026-08-16T12:54:06.961336Z","steps":["trace[1800503437] 'process raft request' (duration: 1.574323716s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:06.961574Z","caller":"traceutil/trace.go:171","msg":"trace[610731581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"249.78792ms","start":"2026-08-16T12:54:06.711776Z","end":"2026-08-16T12:54:06.961564Z","steps":["trace[610731581] 'agreement among raft nodes before linearized reading' (duration: 249.729159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:06.961575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251051523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-16T12:54:06.961628Z","caller":"traceutil/trace.go:171","msg":"trace[1727315720] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:657; }","duration":"1.251149555s","start":"2026-08-16T12:54:05.710462Z","end":"2026-08-16T12:54:06.961612Z","steps":["trace[1727315720] 'agreement among raft nodes before linearized reading' (duration: 1.251029893s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:06.961671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:05.710443Z","time spent":"1.251215786s","remote":"127.0.0.1:48866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-08-16T12:54:06.961788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:05.386686Z","time spent":"1.57491402s","remote":"127.0.0.1:49096","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-08-16T12:54:09.415721Z","caller":"traceutil/trace.go:171","msg":"trace[660334056] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"450.934ms","start":"2026-08-16T12:54:08.964766Z","end":"2026-08-16T12:54:09.4157Z","steps":["trace[660334056] 'read index received' (duration: 450.743746ms)","trace[660334056] 'applied index is now lower than readState.Index' (duration: 188.994µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:09.415845Z","caller":"traceutil/trace.go:171","msg":"trace[590842648] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"504.316614ms","start":"2026-08-16T12:54:08.911518Z","end":"2026-08-16T12:54:09.415835Z","steps":["trace[590842648] 'process raft request' (duration: 504.052919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.415953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.911493Z","time spent":"504.377565ms","remote":"127.0.0.1:49096","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-08-16T12:54:09.416005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.598183ms","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-08-16T12:54:09.41601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.252755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:54:09.416054Z","caller":"traceutil/trace.go:171","msg":"trace[120104771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"450.344247ms","start":"2026-08-16T12:54:08.965699Z","end":"2026-08-16T12:54:09.416044Z","steps":["trace[120104771] 'agreement among raft nodes before linearized reading' (duration: 450.214414ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:09.416052Z","caller":"traceutil/trace.go:171","msg":"trace[793101425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"450.677264ms","start":"2026-08-16T12:54:08.965361Z","end":"2026-08-16T12:54:09.416038Z","steps":["trace[793101425] 'agreement among raft nodes before linearized reading' (duration: 450.481009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.416075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.96568Z","time spent":"450.391728ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:54:09.416089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.965345Z","time spent":"450.736356ms","remote":"127.0.0.1:49096","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-08-16T12:54:09.416493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.718837ms","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-08-16T12:54:09.416543Z","caller":"traceutil/trace.go:171","msg":"trace[411325786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"451.82251ms","start":"2026-08-16T12:54:08.964707Z","end":"2026-08-16T12:54:09.416529Z","steps":["trace[411325786] 'agreement among raft nodes before linearized reading' (duration: 451.130604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.416576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.964685Z","time spent":"451.88612ms","remote":"127.0.0.1:49096","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-08-16T12:54:09.891255Z","caller":"traceutil/trace.go:171","msg":"trace[96131786] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"120.806913ms","start":"2026-08-16T12:54:09.770421Z","end":"2026-08-16T12:54:09.891228Z","steps":["trace[96131786] 'process raft request' (duration: 120.613089ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:09.891598Z","caller":"traceutil/trace.go:171","msg":"trace[587410780] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"118.263427ms","start":"2026-08-16T12:54:09.773323Z","end":"2026-08-16T12:54:09.891586Z","steps":["trace[587410780] 'process raft request' (duration: 117.838837ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:12.986968Z","caller":"traceutil/trace.go:171","msg":"trace[1204025309] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"142.903258ms","start":"2026-08-16T12:54:12.844033Z","end":"2026-08-16T12:54:12.986937Z","steps":["trace[1204025309] 'process raft request' (duration: 120.510206ms)","trace[1204025309] 'compare' (duration: 22.181527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:24.11221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:24.612898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:25.113065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193145578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:54:25.148079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.576818142s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:54:25.148484Z","caller":"traceutil/trace.go:171","msg":"trace[615955420] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"1.537117164s","start":"2026-08-16T12:54:23.611344Z","end":"2026-08-16T12:54:25.148461Z","steps":["trace[615955420] 'read index received' (duration: 1.537056253s)","trace[615955420] 'applied index is now lower than readState.Index' (duration: 59.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:25.148627Z","caller":"traceutil/trace.go:171","msg":"trace[1260835854] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.577438246s","start":"2026-08-16T12:54:23.571146Z","end":"2026-08-16T12:54:25.148584Z","steps":["trace[1260835854] 'process raft request' (duration: 1.57718345s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.148941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507788127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:54:25.148995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452736966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:54:25.149015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53205889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:54:25.149016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410609374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qc6fp\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-16T12:54:25.149053Z","caller":"traceutil/trace.go:171","msg":"trace[1944140182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"1.452819828s","start":"2026-08-16T12:54:23.696216Z","end":"2026-08-16T12:54:25.149036Z","steps":["trace[1944140182] 'agreement among raft nodes before linearized reading' (duration: 1.452741086s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:25.149017Z","caller":"traceutil/trace.go:171","msg":"trace[993583435] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:812; }","duration":"1.507898749s","start":"2026-08-16T12:54:23.6411Z","end":"2026-08-16T12:54:25.148998Z","steps":["trace[993583435] 'agreement among raft nodes before linearized reading' (duration: 1.507763296s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:25.149076Z","caller":"traceutil/trace.go:171","msg":"trace[1940536495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.532172113s","start":"2026-08-16T12:54:23.61688Z","end":"2026-08-16T12:54:25.149052Z","steps":["trace[1940536495] 'agreement among raft nodes before linearized reading' (duration: 1.531949508s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530107767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-16T12:54:25.149106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.641082Z","time spent":"1.508008682s","remote":"127.0.0.1:49250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:25.149132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.616859Z","time spent":"1.532263355s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:54:25.149158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.475897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:25.149232Z","caller":"traceutil/trace.go:171","msg":"trace[1049589762] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:812; }","duration":"234.588589ms","start":"2026-08-16T12:54:24.914632Z","end":"2026-08-16T12:54:25.149221Z","steps":["trace[1049589762] 'agreement among raft nodes before linearized reading' (duration: 234.498287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246301346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:25.149301Z","caller":"traceutil/trace.go:171","msg":"trace[672185578] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:812; }","duration":"1.246387798s","start":"2026-08-16T12:54:23.902902Z","end":"2026-08-16T12:54:25.149289Z","steps":["trace[672185578] 'agreement among raft nodes before linearized reading' (duration: 1.246312226s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.902877Z","time spent":"1.24645794s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:25.148814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53742038s","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-08-16T12:54:25.149475Z","caller":"traceutil/trace.go:171","msg":"trace[275882588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"1.538181367s","start":"2026-08-16T12:54:23.611276Z","end":"2026-08-16T12:54:25.149458Z","steps":["trace[275882588] 'agreement among raft nodes before linearized reading' (duration: 1.537344888s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.611251Z","time spent":"1.538257189s","remote":"127.0.0.1:49096","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-08-16T12:54:25.149156Z","caller":"traceutil/trace.go:171","msg":"trace[595968526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.530210549s","start":"2026-08-16T12:54:23.618925Z","end":"2026-08-16T12:54:25.149136Z","steps":["trace[595968526] 'agreement among raft nodes before linearized reading' (duration: 1.530001174s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294785671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:54:25.149599Z","caller":"traceutil/trace.go:171","msg":"trace[686859851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.294869373s","start":"2026-08-16T12:54:23.854718Z","end":"2026-08-16T12:54:25.149588Z","steps":["trace[686859851] 'agreement among raft nodes before linearized reading' (duration: 1.294587156s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.1496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.618916Z","time spent":"1.530662798s","remote":"127.0.0.1:49096","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-08-16T12:54:25.14963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.854698Z","time spent":"1.294924994s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:54:25.149101Z","caller":"traceutil/trace.go:171","msg":"trace[1303866246] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qc6fp; range_end:; response_count:1; response_revision:812; }","duration":"1.410722845s","start":"2026-08-16T12:54:23.738355Z","end":"2026-08-16T12:54:25.149078Z","steps":["trace[1303866246] 'agreement among raft nodes before linearized reading' (duration: 1.41048471s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.149731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.738333Z","time spent":"1.411383411s","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qc6fp\" "} {"level":"warn","ts":"2026-08-16T12:54:25.149798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.57112Z","time spent":"1.577584289s","remote":"127.0.0.1:49096","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-08-16T12:54:25.149092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:23.696198Z","time spent":"1.4528867s","remote":"127.0.0.1:48854","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-08-16T12:54:25.621047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.069162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:54:25.621151Z","caller":"traceutil/trace.go:171","msg":"trace[1466967825] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"465.755632ms","start":"2026-08-16T12:54:25.155377Z","end":"2026-08-16T12:54:25.621132Z","steps":["trace[1466967825] 'process raft request' (duration: 48.527546ms)","trace[1466967825] 'compare' (duration: 416.866878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:25.621234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.155359Z","time spent":"465.847923ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:25.633751Z","caller":"traceutil/trace.go:171","msg":"trace[637448325] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"478.1801ms","start":"2026-08-16T12:54:25.155542Z","end":"2026-08-16T12:54:25.633722Z","steps":["trace[637448325] 'process raft request' (duration: 477.935914ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:25.63389Z","caller":"traceutil/trace.go:171","msg":"trace[620211258] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"478.161709ms","start":"2026-08-16T12:54:25.155719Z","end":"2026-08-16T12:54:25.633881Z","steps":["trace[620211258] 'process raft request' (duration: 477.947614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.633947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.155693Z","time spent":"478.227451ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:25.634153Z","caller":"traceutil/trace.go:171","msg":"trace[536698800] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:844; }","duration":"471.920609ms","start":"2026-08-16T12:54:25.16221Z","end":"2026-08-16T12:54:25.634131Z","steps":["trace[536698800] 'read index received' (duration: 41.678251ms)","trace[536698800] 'applied index is now lower than readState.Index' (duration: 430.241368ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:25.634165Z","caller":"traceutil/trace.go:171","msg":"trace[169070901] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"471.866198ms","start":"2026-08-16T12:54:25.162271Z","end":"2026-08-16T12:54:25.634137Z","steps":["trace[169070901] 'process raft request' (duration: 471.567241ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:25.634304Z","caller":"traceutil/trace.go:171","msg":"trace[1474410279] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"469.794482ms","start":"2026-08-16T12:54:25.164495Z","end":"2026-08-16T12:54:25.63429Z","steps":["trace[1474410279] 'process raft request' (duration: 469.546407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.634364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.138873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:54:25.634402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.16449Z","time spent":"469.856823ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:25.634426Z","caller":"traceutil/trace.go:171","msg":"trace[2048291452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"472.211536ms","start":"2026-08-16T12:54:25.162196Z","end":"2026-08-16T12:54:25.634408Z","steps":["trace[2048291452] 'agreement among raft nodes before linearized reading' (duration: 472.117274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:25.634464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.162191Z","time spent":"472.264837ms","remote":"127.0.0.1:48854","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-08-16T12:54:25.63489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.155532Z","time spent":"478.330113ms","remote":"127.0.0.1:49096","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-08-16T12:54:25.634469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.162266Z","time spent":"472.031961ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:39.406386Z","caller":"traceutil/trace.go:171","msg":"trace[1513223842] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"196.455654ms","start":"2026-08-16T12:54:39.20991Z","end":"2026-08-16T12:54:39.406366Z","steps":["trace[1513223842] 'read index received' (duration: 196.332281ms)","trace[1513223842] 'applied index is now lower than readState.Index' (duration: 122.333µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:39.406434Z","caller":"traceutil/trace.go:171","msg":"trace[285072765] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"267.782792ms","start":"2026-08-16T12:54:39.138625Z","end":"2026-08-16T12:54:39.406407Z","steps":["trace[285072765] 'process raft request' (duration: 267.604768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:39.406544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.624698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:54:39.406571Z","caller":"traceutil/trace.go:171","msg":"trace[128788247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"196.693779ms","start":"2026-08-16T12:54:39.20987Z","end":"2026-08-16T12:54:39.406563Z","steps":["trace[128788247] 'agreement among raft nodes before linearized reading' (duration: 196.573047ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:39.588178Z","caller":"traceutil/trace.go:171","msg":"trace[1647369058] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"175.942686ms","start":"2026-08-16T12:54:39.412215Z","end":"2026-08-16T12:54:39.588157Z","steps":["trace[1647369058] 'process raft request' (duration: 85.85008ms)","trace[1647369058] 'compare' (duration: 89.954483ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:39.590627Z","caller":"traceutil/trace.go:171","msg":"trace[1052323305] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"178.125566ms","start":"2026-08-16T12:54:39.41249Z","end":"2026-08-16T12:54:39.590616Z","steps":["trace[1052323305] 'process raft request' (duration: 177.963622ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:39.590646Z","caller":"traceutil/trace.go:171","msg":"trace[245252034] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"176.023228ms","start":"2026-08-16T12:54:39.414597Z","end":"2026-08-16T12:54:39.590621Z","steps":["trace[245252034] 'process raft request' (duration: 175.988007ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:39.590663Z","caller":"traceutil/trace.go:171","msg":"trace[558571194] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"177.996472ms","start":"2026-08-16T12:54:39.412643Z","end":"2026-08-16T12:54:39.590639Z","steps":["trace[558571194] 'process raft request' (duration: 177.90432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:39.996105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.047502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-16T12:54:39.996151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.843241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:54:39.996197Z","caller":"traceutil/trace.go:171","msg":"trace[310134757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"273.185585ms","start":"2026-08-16T12:54:39.722984Z","end":"2026-08-16T12:54:39.99617Z","steps":["trace[310134757] 'range keys from in-memory index tree' (duration: 272.854428ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:39.996212Z","caller":"traceutil/trace.go:171","msg":"trace[1338564661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"284.951474ms","start":"2026-08-16T12:54:39.711245Z","end":"2026-08-16T12:54:39.996197Z","steps":["trace[1338564661] 'range keys from in-memory index tree' (duration: 284.704148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:39.996217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.611084ms","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-08-16T12:54:39.996299Z","caller":"traceutil/trace.go:171","msg":"trace[885197557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"301.748988ms","start":"2026-08-16T12:54:39.694526Z","end":"2026-08-16T12:54:39.996275Z","steps":["trace[885197557] 'range keys from in-memory index tree' (duration: 301.462271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:39.996363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.328349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:54:39.99641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:39.694488Z","time spent":"301.909471ms","remote":"127.0.0.1:49096","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-08-16T12:54:39.996424Z","caller":"traceutil/trace.go:171","msg":"trace[1657190805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"273.495693ms","start":"2026-08-16T12:54:39.722915Z","end":"2026-08-16T12:54:39.99641Z","steps":["trace[1657190805] 'range keys from in-memory index tree' (duration: 273.174925ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:40.006877Z","caller":"traceutil/trace.go:171","msg":"trace[1174460081] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"125.831432ms","start":"2026-08-16T12:54:39.881034Z","end":"2026-08-16T12:54:40.006865Z","steps":["trace[1174460081] 'process raft request' (duration: 125.73953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:40.253009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.811695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:54:40.253103Z","caller":"traceutil/trace.go:171","msg":"trace[81207327] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:957; }","duration":"112.98021ms","start":"2026-08-16T12:54:40.140102Z","end":"2026-08-16T12:54:40.253083Z","steps":["trace[81207327] 'count revisions from in-memory index tree' (duration: 112.741353ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:40.913205Z","caller":"traceutil/trace.go:171","msg":"trace[1158879556] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"333.092943ms","start":"2026-08-16T12:54:40.580062Z","end":"2026-08-16T12:54:40.913155Z","steps":["trace[1158879556] 'read index received' (duration: 332.857107ms)","trace[1158879556] 'applied index is now lower than readState.Index' (duration: 234.626µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:40.913246Z","caller":"traceutil/trace.go:171","msg":"trace[1537550143] transaction","detail":"{read_only:false; number_of_response:1; response_revision:958; }","duration":"395.761403ms","start":"2026-08-16T12:54:40.51746Z","end":"2026-08-16T12:54:40.913221Z","steps":["trace[1537550143] 'process raft request' (duration: 395.538758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:40.913437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.358728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-fq6d8\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-08-16T12:54:40.913481Z","caller":"traceutil/trace.go:171","msg":"trace[49359259] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-fq6d8; range_end:; response_count:1; response_revision:958; }","duration":"333.446571ms","start":"2026-08-16T12:54:40.580022Z","end":"2026-08-16T12:54:40.913469Z","steps":["trace[49359259] 'agreement among raft nodes before linearized reading' (duration: 333.278687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:40.913485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:40.517449Z","time spent":"395.859685ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":76,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-08-16T12:54:40.913524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:40.580001Z","time spent":"333.514572ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4906,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-fq6d8\" "} {"level":"info","ts":"2026-08-16T12:54:41.026334Z","caller":"traceutil/trace.go:171","msg":"trace[1570028763] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"112.99168ms","start":"2026-08-16T12:54:40.913275Z","end":"2026-08-16T12:54:41.026267Z","steps":["trace[1570028763] 'read index received' (duration: 98.925639ms)","trace[1570028763] 'applied index is now lower than readState.Index' (duration: 14.064561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:41.026427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.495819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:41.026492Z","caller":"traceutil/trace.go:171","msg":"trace[53840853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"332.592312ms","start":"2026-08-16T12:54:40.693885Z","end":"2026-08-16T12:54:41.026477Z","steps":["trace[53840853] 'agreement among raft nodes before linearized reading' (duration: 332.482689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:41.02654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:40.693868Z","time spent":"332.663633ms","remote":"127.0.0.1:48854","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-08-16T12:54:41.028349Z","caller":"traceutil/trace.go:171","msg":"trace[1655988408] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"105.967668ms","start":"2026-08-16T12:54:40.922359Z","end":"2026-08-16T12:54:41.028326Z","steps":["trace[1655988408] 'process raft request' (duration: 105.700822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:13.733088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037852489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:13.733226Z","caller":"traceutil/trace.go:171","msg":"trace[1332366680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"1.038007992s","start":"2026-08-16T12:55:12.695152Z","end":"2026-08-16T12:55:13.73316Z","steps":["trace[1332366680] 'range keys from in-memory index tree' (duration: 1.037761957s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:13.733289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:12.695119Z","time spent":"1.038155026s","remote":"127.0.0.1:48854","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-08-16T12:55:14.657273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.971629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:14.657507Z","caller":"traceutil/trace.go:171","msg":"trace[376066219] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"238.556294ms","start":"2026-08-16T12:55:14.418929Z","end":"2026-08-16T12:55:14.657486Z","steps":["trace[376066219] 'process raft request' (duration: 100.060543ms)","trace[376066219] 'compare' (duration: 137.760655ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:14.657869Z","caller":"traceutil/trace.go:171","msg":"trace[22273154] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1117; }","duration":"213.773089ms","start":"2026-08-16T12:55:14.444078Z","end":"2026-08-16T12:55:14.657851Z","steps":["trace[22273154] 'read index received' (duration: 39.225095ms)","trace[22273154] 'applied index is now lower than readState.Index' (duration: 174.545654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:14.658074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.994294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:55:14.65811Z","caller":"traceutil/trace.go:171","msg":"trace[297262373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"214.056695ms","start":"2026-08-16T12:55:14.444044Z","end":"2026-08-16T12:55:14.6581Z","steps":["trace[297262373] 'agreement among raft nodes before linearized reading' (duration: 213.912812ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:14.85501Z","caller":"traceutil/trace.go:171","msg":"trace[1764877253] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"107.124925ms","start":"2026-08-16T12:55:14.747859Z","end":"2026-08-16T12:55:14.854984Z","steps":["trace[1764877253] 'read index received' (duration: 30.135868ms)","trace[1764877253] 'applied index is now lower than readState.Index' (duration: 76.987967ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:14.855157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.281038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:14.855159Z","caller":"traceutil/trace.go:171","msg":"trace[1386488895] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"193.056297ms","start":"2026-08-16T12:55:14.662062Z","end":"2026-08-16T12:55:14.855119Z","steps":["trace[1386488895] 'process raft request' (duration: 115.974188ms)","trace[1386488895] 'compare' (duration: 76.833893ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:14.855273Z","caller":"traceutil/trace.go:171","msg":"trace[2102195254] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1078; }","duration":"107.415831ms","start":"2026-08-16T12:55:14.747809Z","end":"2026-08-16T12:55:14.855225Z","steps":["trace[2102195254] 'agreement among raft nodes before linearized reading' (duration: 107.262998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:16.351689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.803849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:16.351812Z","caller":"traceutil/trace.go:171","msg":"trace[19080783] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"496.003871ms","start":"2026-08-16T12:55:15.855788Z","end":"2026-08-16T12:55:16.351792Z","steps":["trace[19080783] 'process raft request' (duration: 357.010858ms)","trace[19080783] 'compare' (duration: 138.656395ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:16.351877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:15.855769Z","time spent":"496.076352ms","remote":"127.0.0.1:48866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:17.249432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.033322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:17.249557Z","caller":"traceutil/trace.go:171","msg":"trace[278676580] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"1.387887987s","start":"2026-08-16T12:55:15.861648Z","end":"2026-08-16T12:55:17.249535Z","steps":["trace[278676580] 'process raft request' (duration: 668.62309ms)","trace[278676580] 'compare' (duration: 718.856148ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:17.249616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:15.861625Z","time spent":"1.387963419s","remote":"127.0.0.1:49096","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-08-16T12:55:17.712838Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182580941s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-16T12:55:17.918041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.959147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:17.918538Z","caller":"traceutil/trace.go:171","msg":"trace[287129491] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"1.690912294s","start":"2026-08-16T12:55:16.227606Z","end":"2026-08-16T12:55:17.918519Z","steps":["trace[287129491] 'process raft request' (duration: 1.485387344s)","trace[287129491] 'compare' (duration: 204.727962ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:17.918576Z","caller":"traceutil/trace.go:171","msg":"trace[5784023] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1122; }","duration":"1.628463798s","start":"2026-08-16T12:55:16.290088Z","end":"2026-08-16T12:55:17.918552Z","steps":["trace[5784023] 'read index received' (duration: 240.191321ms)","trace[5784023] 'applied index is now lower than readState.Index' (duration: 1.388270787s)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:17.918621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.227588Z","time spent":"1.690999326s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:17.918744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564816806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-08-16T12:55:17.918785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256650752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:17.918795Z","caller":"traceutil/trace.go:171","msg":"trace[2145113908] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1082; }","duration":"1.564896868s","start":"2026-08-16T12:55:16.353885Z","end":"2026-08-16T12:55:17.918781Z","steps":["trace[2145113908] 'agreement among raft nodes before linearized reading' (duration: 1.564813216s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:17.918804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059137364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:55:17.918814Z","caller":"traceutil/trace.go:171","msg":"trace[2050488993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"1.256721504s","start":"2026-08-16T12:55:16.662083Z","end":"2026-08-16T12:55:17.918805Z","steps":["trace[2050488993] 'agreement among raft nodes before linearized reading' (duration: 1.256613511s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:17.918831Z","caller":"traceutil/trace.go:171","msg":"trace[1531028045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"1.059270397s","start":"2026-08-16T12:55:16.859552Z","end":"2026-08-16T12:55:17.918822Z","steps":["trace[1531028045] 'agreement among raft nodes before linearized reading' (duration: 1.059143664s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:17.918831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.353872Z","time spent":"1.564951699s","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-16T12:55:17.918843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.662061Z","time spent":"1.256777025s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:17.918855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.859527Z","time spent":"1.059322118s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:17.918921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431471792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-16T12:55:17.918939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.189776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:55:17.918744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628662532s","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-08-16T12:55:17.918947Z","caller":"traceutil/trace.go:171","msg":"trace[1730104425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"1.431569985s","start":"2026-08-16T12:55:16.48737Z","end":"2026-08-16T12:55:17.91894Z","steps":["trace[1730104425] 'agreement among raft nodes before linearized reading' (duration: 1.431499883s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:17.918957Z","caller":"traceutil/trace.go:171","msg":"trace[1304369523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"220.244957ms","start":"2026-08-16T12:55:17.698706Z","end":"2026-08-16T12:55:17.918951Z","steps":["trace[1304369523] 'agreement among raft nodes before linearized reading' (duration: 220.218396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:17.918968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.487341Z","time spent":"1.431622435s","remote":"127.0.0.1:49096","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-08-16T12:55:17.918967Z","caller":"traceutil/trace.go:171","msg":"trace[325034610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"1.628925058s","start":"2026-08-16T12:55:16.290034Z","end":"2026-08-16T12:55:17.918959Z","steps":["trace[325034610] 'agreement among raft nodes before linearized reading' (duration: 1.628625441s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:17.919021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:16.29001Z","time spent":"1.62900597s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:55:18.424299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193146166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:55:18.922676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.165786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:18.922777Z","caller":"traceutil/trace.go:171","msg":"trace[450031059] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"999.829871ms","start":"2026-08-16T12:55:17.922929Z","end":"2026-08-16T12:55:18.922758Z","steps":["trace[450031059] 'process raft request' (duration: 835.501171ms)","trace[450031059] 'compare' (duration: 163.397429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:18.922835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:17.922919Z","time spent":"999.888962ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:18.995644Z","caller":"traceutil/trace.go:171","msg":"trace[945404331] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.072648423s","start":"2026-08-16T12:55:17.922969Z","end":"2026-08-16T12:55:18.995617Z","steps":["trace[945404331] 'process raft request' (duration: 1.071679221s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:18.995794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:17.922958Z","time spent":"1.072758265s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:18.995977Z","caller":"traceutil/trace.go:171","msg":"trace[252671850] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.072722224s","start":"2026-08-16T12:55:17.923227Z","end":"2026-08-16T12:55:18.995949Z","steps":["trace[252671850] 'process raft request' (duration: 1.072329975s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:18.996147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:17.923217Z","time spent":"1.072846697s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:18.996199Z","caller":"traceutil/trace.go:171","msg":"trace[1036406824] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.072839957s","start":"2026-08-16T12:55:17.923346Z","end":"2026-08-16T12:55:18.996186Z","steps":["trace[1036406824] 'process raft request' (duration: 1.072508228s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:18.996149Z","caller":"traceutil/trace.go:171","msg":"trace[1878973096] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1125; }","duration":"1.072536871s","start":"2026-08-16T12:55:17.92359Z","end":"2026-08-16T12:55:18.996127Z","steps":["trace[1878973096] 'read index received' (duration: 834.853285ms)","trace[1878973096] 'applied index is now lower than readState.Index' (duration: 237.681765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:18.996248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072647533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-16T12:55:18.996267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:17.923325Z","time spent":"1.072905369s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:18.996296Z","caller":"traceutil/trace.go:171","msg":"trace[1455812013] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1086; }","duration":"1.072721384s","start":"2026-08-16T12:55:17.923562Z","end":"2026-08-16T12:55:18.996283Z","steps":["trace[1455812013] 'agreement among raft nodes before linearized reading' (duration: 1.072622272s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:18.996336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:17.923553Z","time spent":"1.072772676s","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-16T12:55:18.996393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.632019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:55:18.996388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.473682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:55:18.996426Z","caller":"traceutil/trace.go:171","msg":"trace[1854899696] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1086; }","duration":"161.705811ms","start":"2026-08-16T12:55:18.83471Z","end":"2026-08-16T12:55:18.996416Z","steps":["trace[1854899696] 'agreement among raft nodes before linearized reading' (duration: 161.64887ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:18.996444Z","caller":"traceutil/trace.go:171","msg":"trace[967113984] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1086; }","duration":"446.552423ms","start":"2026-08-16T12:55:18.549877Z","end":"2026-08-16T12:55:18.996429Z","steps":["trace[967113984] 'agreement among raft nodes before linearized reading' (duration: 446.429791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:18.996479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:18.549817Z","time spent":"446.654215ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:55:19.53084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.189036ms","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-08-16T12:55:19.530928Z","caller":"traceutil/trace.go:171","msg":"trace[2120871815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"277.33404ms","start":"2026-08-16T12:55:19.253576Z","end":"2026-08-16T12:55:19.53091Z","steps":["trace[2120871815] 'range keys from in-memory index tree' (duration: 277.027153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:19.815423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.509684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:19.815576Z","caller":"traceutil/trace.go:171","msg":"trace[1955929033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"118.722859ms","start":"2026-08-16T12:55:19.696833Z","end":"2026-08-16T12:55:19.815556Z","steps":["trace[1955929033] 'range keys from in-memory index tree' (duration: 118.414073ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:21.306594Z","caller":"traceutil/trace.go:171","msg":"trace[1232587896] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"606.930572ms","start":"2026-08-16T12:55:20.699639Z","end":"2026-08-16T12:55:21.30657Z","steps":["trace[1232587896] 'process raft request' (duration: 606.782199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:21.306739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:20.699615Z","time spent":"607.050084ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:22.138259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.892176ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-16T12:55:22.138264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.835771ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:22.138389Z","caller":"traceutil/trace.go:171","msg":"trace[1545876004] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1089; }","duration":"153.979995ms","start":"2026-08-16T12:55:21.984394Z","end":"2026-08-16T12:55:22.138374Z","steps":["trace[1545876004] 'range keys from in-memory index tree' (duration: 153.81755ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:22.138399Z","caller":"traceutil/trace.go:171","msg":"trace[592560148] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"1.210501289s","start":"2026-08-16T12:55:20.92788Z","end":"2026-08-16T12:55:22.138382Z","steps":["trace[592560148] 'read index received' (duration: 378.54339ms)","trace[592560148] 'applied index is now lower than readState.Index' (duration: 831.956509ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:22.138863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210970589s","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-08-16T12:55:22.138964Z","caller":"traceutil/trace.go:171","msg":"trace[462006555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"1.211114733s","start":"2026-08-16T12:55:20.927835Z","end":"2026-08-16T12:55:22.138949Z","steps":["trace[462006555] 'agreement among raft nodes before linearized reading' (duration: 1.210618002s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.139031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:20.927805Z","time spent":"1.211214405s","remote":"127.0.0.1:49096","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-08-16T12:55:22.201926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201979414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:55:22.201927Z","caller":"traceutil/trace.go:171","msg":"trace[1021264572] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"236.740504ms","start":"2026-08-16T12:55:21.965155Z","end":"2026-08-16T12:55:22.201895Z","steps":["trace[1021264572] 'process raft request' (duration: 236.469968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.201977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.311442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-16T12:55:22.202168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202195109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:22.202216Z","caller":"traceutil/trace.go:171","msg":"trace[91425525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.202277571s","start":"2026-08-16T12:55:20.999929Z","end":"2026-08-16T12:55:22.202207Z","steps":["trace[91425525] 'agreement among raft nodes before linearized reading' (duration: 1.202147118s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.202286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:20.999912Z","time spent":"1.202364564s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:22.201978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.060521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:22.202216Z","caller":"traceutil/trace.go:171","msg":"trace[1060655508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"639.593468ms","start":"2026-08-16T12:55:21.562601Z","end":"2026-08-16T12:55:22.202194Z","steps":["trace[1060655508] 'agreement among raft nodes before linearized reading' (duration: 639.214249ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:22.202471Z","caller":"traceutil/trace.go:171","msg":"trace[1024209795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"506.596483ms","start":"2026-08-16T12:55:21.695854Z","end":"2026-08-16T12:55:22.20245Z","steps":["trace[1024209795] 'agreement among raft nodes before linearized reading' (duration: 506.059511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.202477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:21.562581Z","time spent":"639.880855ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:55:22.202541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:21.695834Z","time spent":"506.694355ms","remote":"127.0.0.1:48840","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-08-16T12:55:22.202001Z","caller":"traceutil/trace.go:171","msg":"trace[885475461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.202095967s","start":"2026-08-16T12:55:20.999884Z","end":"2026-08-16T12:55:22.20198Z","steps":["trace[885475461] 'agreement among raft nodes before linearized reading' (duration: 1.201895412s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.202637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:20.999867Z","time spent":"1.202753892s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:22.202049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011076687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:22.202847Z","caller":"traceutil/trace.go:171","msg":"trace[2006980072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"1.011897006s","start":"2026-08-16T12:55:21.190938Z","end":"2026-08-16T12:55:22.202835Z","steps":["trace[2006980072] 'agreement among raft nodes before linearized reading' (duration: 1.011095528s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.202889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:21.190904Z","time spent":"1.011975668s","remote":"127.0.0.1:48854","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-08-16T12:55:22.202114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202095307s","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-08-16T12:55:22.203408Z","caller":"traceutil/trace.go:171","msg":"trace[1311988733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.202970687s","start":"2026-08-16T12:55:20.999952Z","end":"2026-08-16T12:55:22.202922Z","steps":["trace[1311988733] 'agreement among raft nodes before linearized reading' (duration: 1.202035756s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.20371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:20.999925Z","time spent":"1.203736885s","remote":"127.0.0.1:49096","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-08-16T12:55:22.728604Z","caller":"traceutil/trace.go:171","msg":"trace[2067120002] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"585.530394ms","start":"2026-08-16T12:55:22.143047Z","end":"2026-08-16T12:55:22.728577Z","steps":["trace[2067120002] 'process raft request' (duration: 585.33005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:22.728769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.143025Z","time spent":"585.663608ms","remote":"127.0.0.1:49096","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-08-16T12:55:23.517088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.038214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:23.517428Z","caller":"traceutil/trace.go:171","msg":"trace[2107177384] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1134; }","duration":"821.837328ms","start":"2026-08-16T12:55:22.695574Z","end":"2026-08-16T12:55:23.517411Z","steps":["trace[2107177384] 'read index received' (duration: 32.900441ms)","trace[2107177384] 'applied index is now lower than readState.Index' (duration: 788.935837ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:23.517581Z","caller":"traceutil/trace.go:171","msg":"trace[492629016] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.30995733s","start":"2026-08-16T12:55:22.207609Z","end":"2026-08-16T12:55:23.517566Z","steps":["trace[492629016] 'process raft request' (duration: 1.309645361s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:23.517586Z","caller":"traceutil/trace.go:171","msg":"trace[691319986] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"1.31000814s","start":"2026-08-16T12:55:22.207546Z","end":"2026-08-16T12:55:23.517555Z","steps":["trace[691319986] 'process raft request' (duration: 1.309654041s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:23.517664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.207591Z","time spent":"1.310021911s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:23.517735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.207533Z","time spent":"1.310125503s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:23.517979Z","caller":"traceutil/trace.go:171","msg":"trace[1938600619] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"1.311563735s","start":"2026-08-16T12:55:22.206389Z","end":"2026-08-16T12:55:23.517953Z","steps":["trace[1938600619] 'process raft request' (duration: 827.589099ms)","trace[1938600619] 'compare' (duration: 482.932082ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:23.51811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.206339Z","time spent":"1.311709708s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:23.518436Z","caller":"traceutil/trace.go:171","msg":"trace[887745942] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.310660685s","start":"2026-08-16T12:55:22.207761Z","end":"2026-08-16T12:55:23.518422Z","steps":["trace[887745942] 'process raft request' (duration: 1.30960937s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:23.518526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.207751Z","time spent":"1.310720546s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:23.518788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.186958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:23.518877Z","caller":"traceutil/trace.go:171","msg":"trace[530831492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"823.323572ms","start":"2026-08-16T12:55:22.695535Z","end":"2026-08-16T12:55:23.518859Z","steps":["trace[530831492] 'agreement among raft nodes before linearized reading' (duration: 822.032843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:23.518923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:22.695515Z","time spent":"823.399224ms","remote":"127.0.0.1:48854","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-08-16T12:55:24.865444Z","caller":"traceutil/trace.go:171","msg":"trace[5640653] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"130.701294ms","start":"2026-08-16T12:55:24.734718Z","end":"2026-08-16T12:55:24.86542Z","steps":["trace[5640653] 'process raft request' (duration: 130.53616ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:38.20767Z","caller":"traceutil/trace.go:171","msg":"trace[1871334311] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"194.854206ms","start":"2026-08-16T12:55:38.012777Z","end":"2026-08-16T12:55:38.207631Z","steps":["trace[1871334311] 'process raft request' (duration: 102.75895ms)","trace[1871334311] 'compare' (duration: 91.920242ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:38.212286Z","caller":"traceutil/trace.go:171","msg":"trace[1674735655] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"196.940083ms","start":"2026-08-16T12:55:38.015324Z","end":"2026-08-16T12:55:38.212264Z","steps":["trace[1674735655] 'process raft request' (duration: 195.982102ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:38.212716Z","caller":"traceutil/trace.go:171","msg":"trace[1471950251] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"195.223593ms","start":"2026-08-16T12:55:38.017483Z","end":"2026-08-16T12:55:38.212707Z","steps":["trace[1471950251] 'process raft request' (duration: 194.222681ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:38.213553Z","caller":"traceutil/trace.go:171","msg":"trace[1142740941] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1225; }","duration":"193.889945ms","start":"2026-08-16T12:55:38.019632Z","end":"2026-08-16T12:55:38.213522Z","steps":["trace[1142740941] 'read index received' (duration: 96.001756ms)","trace[1142740941] 'applied index is now lower than readState.Index' (duration: 97.886519ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:38.21378Z","caller":"traceutil/trace.go:171","msg":"trace[1924021158] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"193.907985ms","start":"2026-08-16T12:55:38.01983Z","end":"2026-08-16T12:55:38.213738Z","steps":["trace[1924021158] 'process raft request' (duration: 193.26313ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:38.214079Z","caller":"traceutil/trace.go:171","msg":"trace[1449441481] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"196.373051ms","start":"2026-08-16T12:55:38.017694Z","end":"2026-08-16T12:55:38.214067Z","steps":["trace[1449441481] 'process raft request' (duration: 194.706713ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:38.214459Z","caller":"traceutil/trace.go:171","msg":"trace[30681602] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"188.766018ms","start":"2026-08-16T12:55:38.02566Z","end":"2026-08-16T12:55:38.214426Z","steps":["trace[30681602] 'process raft request' (duration: 187.688173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:38.215063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.399688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-08-16T12:55:38.215138Z","caller":"traceutil/trace.go:171","msg":"trace[1231063668] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1182; }","duration":"195.511661ms","start":"2026-08-16T12:55:38.019607Z","end":"2026-08-16T12:55:38.215118Z","steps":["trace[1231063668] 'agreement among raft nodes before linearized reading' (duration: 194.030628ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:39.765264Z","caller":"traceutil/trace.go:171","msg":"trace[786699536] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"116.171554ms","start":"2026-08-16T12:55:39.649075Z","end":"2026-08-16T12:55:39.765246Z","steps":["trace[786699536] 'read index received' (duration: 115.914939ms)","trace[786699536] 'applied index is now lower than readState.Index' (duration: 255.755µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:39.765386Z","caller":"traceutil/trace.go:171","msg":"trace[94756929] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"119.865498ms","start":"2026-08-16T12:55:39.645446Z","end":"2026-08-16T12:55:39.765312Z","steps":["trace[94756929] 'process raft request' (duration: 119.587302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:39.76566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.551904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:39.76569Z","caller":"traceutil/trace.go:171","msg":"trace[1283580638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"116.652916ms","start":"2026-08-16T12:55:39.649028Z","end":"2026-08-16T12:55:39.765681Z","steps":["trace[1283580638] 'agreement among raft nodes before linearized reading' (duration: 116.352259ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:40.493127Z","caller":"traceutil/trace.go:171","msg":"trace[1656890599] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"120.094834ms","start":"2026-08-16T12:55:40.373009Z","end":"2026-08-16T12:55:40.493104Z","steps":["trace[1656890599] 'process raft request' (duration: 119.772017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:42.195942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193146426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:55:42.688011Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156148199s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:55:42.688378Z","caller":"traceutil/trace.go:171","msg":"trace[2132288544] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"993.408935ms","start":"2026-08-16T12:55:41.694942Z","end":"2026-08-16T12:55:42.688351Z","steps":["trace[2132288544] 'read index received' (duration: 993.284812ms)","trace[2132288544] 'applied index is now lower than readState.Index' (duration: 121.233µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:42.688438Z","caller":"traceutil/trace.go:171","msg":"trace[1019961695] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"1.156665891s","start":"2026-08-16T12:55:41.531742Z","end":"2026-08-16T12:55:42.688408Z","steps":["trace[1019961695] 'process raft request' (duration: 1.156445086s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:42.688527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.561838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:42.688563Z","caller":"traceutil/trace.go:171","msg":"trace[1488073796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"993.6415ms","start":"2026-08-16T12:55:41.69491Z","end":"2026-08-16T12:55:42.688552Z","steps":["trace[1488073796] 'agreement among raft nodes before linearized reading' (duration: 993.566128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:42.688598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.694895Z","time spent":"993.695831ms","remote":"127.0.0.1:48854","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-08-16T12:55:42.6886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.531717Z","time spent":"1.156804614s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:43.598796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.701834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:43.598885Z","caller":"traceutil/trace.go:171","msg":"trace[100740186] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"910.421786ms","start":"2026-08-16T12:55:42.688448Z","end":"2026-08-16T12:55:43.59887Z","steps":["trace[100740186] 'read index received' (duration: 190.514997ms)","trace[100740186] 'applied index is now lower than readState.Index' (duration: 719.905699ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:43.598964Z","caller":"traceutil/trace.go:171","msg":"trace[1241592822] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"1.099604713s","start":"2026-08-16T12:55:42.499347Z","end":"2026-08-16T12:55:43.598952Z","steps":["trace[1241592822] 'process raft request' (duration: 379.663983ms)","trace[1241592822] 'compare' (duration: 719.52593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:43.599015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:42.49932Z","time spent":"1.099666754s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:43.599256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659253492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:55:43.599253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.124026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:43.599303Z","caller":"traceutil/trace.go:171","msg":"trace[1674617124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1204; }","duration":"1.659326764s","start":"2026-08-16T12:55:41.939965Z","end":"2026-08-16T12:55:43.599292Z","steps":["trace[1674617124] 'agreement among raft nodes before linearized reading' (duration: 1.659248882s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.939952Z","time spent":"1.659381995s","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:55:43.599331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819661524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:55:43.599339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815786116s","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-08-16T12:55:43.599393Z","caller":"traceutil/trace.go:171","msg":"trace[460457992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"1.819753675s","start":"2026-08-16T12:55:41.779624Z","end":"2026-08-16T12:55:43.599378Z","steps":["trace[460457992] 'agreement among raft nodes before linearized reading' (duration: 1.81954838s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:43.599404Z","caller":"traceutil/trace.go:171","msg":"trace[55339757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"1.815869537s","start":"2026-08-16T12:55:41.783518Z","end":"2026-08-16T12:55:43.599387Z","steps":["trace[55339757] 'agreement among raft nodes before linearized reading' (duration: 1.815658623s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.816641735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-16T12:55:43.599455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.783513Z","time spent":"1.815933589s","remote":"127.0.0.1:49096","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-08-16T12:55:43.599464Z","caller":"traceutil/trace.go:171","msg":"trace[219966205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"1.816703036s","start":"2026-08-16T12:55:41.782746Z","end":"2026-08-16T12:55:43.599449Z","steps":["trace[219966205] 'agreement among raft nodes before linearized reading' (duration: 1.816570303s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83049536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-16T12:55:43.599502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.782738Z","time spent":"1.816756308s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:55:43.599513Z","caller":"traceutil/trace.go:171","msg":"trace[1143408846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"1.830569012s","start":"2026-08-16T12:55:41.768932Z","end":"2026-08-16T12:55:43.599501Z","steps":["trace[1143408846] 'agreement among raft nodes before linearized reading' (duration: 1.830442429s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.77961Z","time spent":"1.819831417s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:43.599555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:41.768916Z","time spent":"1.830628283s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-16T12:55:43.599343Z","caller":"traceutil/trace.go:171","msg":"trace[690741509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"909.253029ms","start":"2026-08-16T12:55:42.69006Z","end":"2026-08-16T12:55:43.599313Z","steps":["trace[690741509] 'agreement among raft nodes before linearized reading' (duration: 909.129786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.108145ms","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-08-16T12:55:43.599602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:42.690037Z","time spent":"909.556815ms","remote":"127.0.0.1:48840","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-08-16T12:55:43.599634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.138002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:55:43.59964Z","caller":"traceutil/trace.go:171","msg":"trace[49434956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"605.173587ms","start":"2026-08-16T12:55:42.994455Z","end":"2026-08-16T12:55:43.599629Z","steps":["trace[49434956] 'agreement among raft nodes before linearized reading' (duration: 605.049844ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:43.599669Z","caller":"traceutil/trace.go:171","msg":"trace[2000068072] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1204; }","duration":"788.208683ms","start":"2026-08-16T12:55:42.81145Z","end":"2026-08-16T12:55:43.599659Z","steps":["trace[2000068072] 'agreement among raft nodes before linearized reading' (duration: 788.131342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:43.599677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:42.994441Z","time spent":"605.227998ms","remote":"127.0.0.1:49096","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-08-16T12:55:43.599697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:42.811431Z","time spent":"788.259274ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:55:47.75287Z","caller":"traceutil/trace.go:171","msg":"trace[1936893248] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"135.831103ms","start":"2026-08-16T12:55:47.617021Z","end":"2026-08-16T12:55:47.752852Z","steps":["trace[1936893248] 'read index received' (duration: 135.627518ms)","trace[1936893248] 'applied index is now lower than readState.Index' (duration: 202.745µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:47.75291Z","caller":"traceutil/trace.go:171","msg":"trace[278858183] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"141.810989ms","start":"2026-08-16T12:55:47.611067Z","end":"2026-08-16T12:55:47.752878Z","steps":["trace[278858183] 'process raft request' (duration: 141.671145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:47.753059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.038177ms","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-08-16T12:55:47.753081Z","caller":"traceutil/trace.go:171","msg":"trace[15302194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"136.110939ms","start":"2026-08-16T12:55:47.616964Z","end":"2026-08-16T12:55:47.753075Z","steps":["trace[15302194] 'agreement among raft nodes before linearized reading' (duration: 135.983566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:47.753262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.116109ms","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-08-16T12:55:47.753287Z","caller":"traceutil/trace.go:171","msg":"trace[1597177827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"125.18817ms","start":"2026-08-16T12:55:47.628093Z","end":"2026-08-16T12:55:47.753282Z","steps":["trace[1597177827] 'agreement among raft nodes before linearized reading' (duration: 125.061657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:47.753336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.554886ms","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-08-16T12:55:47.75344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.164812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:47.753459Z","caller":"traceutil/trace.go:171","msg":"trace[1862039553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"126.184633ms","start":"2026-08-16T12:55:47.62727Z","end":"2026-08-16T12:55:47.753455Z","steps":["trace[1862039553] 'agreement among raft nodes before linearized reading' (duration: 126.138442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:47.753485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.383657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:55:47.753524Z","caller":"traceutil/trace.go:171","msg":"trace[2052102819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"126.459139ms","start":"2026-08-16T12:55:47.627059Z","end":"2026-08-16T12:55:47.753518Z","steps":["trace[2052102819] 'agreement among raft nodes before linearized reading' (duration: 126.391267ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:47.75343Z","caller":"traceutil/trace.go:171","msg":"trace[1170037453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"128.73668ms","start":"2026-08-16T12:55:47.624671Z","end":"2026-08-16T12:55:47.753407Z","steps":["trace[1170037453] 'agreement among raft nodes before linearized reading' (duration: 128.368782ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:47.900685Z","caller":"traceutil/trace.go:171","msg":"trace[718498122] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"134.42083ms","start":"2026-08-16T12:55:47.766239Z","end":"2026-08-16T12:55:47.90066Z","steps":["trace[718498122] 'process raft request' (duration: 83.371457ms)","trace[718498122] 'compare' (duration: 50.867398ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:47.900898Z","caller":"traceutil/trace.go:171","msg":"trace[1986943467] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"134.617585ms","start":"2026-08-16T12:55:47.766257Z","end":"2026-08-16T12:55:47.900874Z","steps":["trace[1986943467] 'process raft request' (duration: 134.365839ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:47.90138Z","caller":"traceutil/trace.go:171","msg":"trace[927358323] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"135.059085ms","start":"2026-08-16T12:55:47.766297Z","end":"2026-08-16T12:55:47.901356Z","steps":["trace[927358323] 'process raft request' (duration: 134.490812ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:47.901425Z","caller":"traceutil/trace.go:171","msg":"trace[2103309722] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"135.071605ms","start":"2026-08-16T12:55:47.766339Z","end":"2026-08-16T12:55:47.901411Z","steps":["trace[2103309722] 'process raft request' (duration: 134.920622ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:47.901413Z","caller":"traceutil/trace.go:171","msg":"trace[1515895143] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"132.958807ms","start":"2026-08-16T12:55:47.768431Z","end":"2026-08-16T12:55:47.90139Z","steps":["trace[1515895143] 'process raft request' (duration: 132.915356ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:49.295787Z","caller":"traceutil/trace.go:171","msg":"trace[2096050587] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"492.196385ms","start":"2026-08-16T12:55:48.803572Z","end":"2026-08-16T12:55:49.295769Z","steps":["trace[2096050587] 'process raft request' (duration: 492.088152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:49.295901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:48.803557Z","time spent":"492.285347ms","remote":"127.0.0.1:49096","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-08-16T12:55:50.405617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193146477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:55:50.886727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128396597s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-16T12:55:50.945713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251451759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:50.94579Z","caller":"traceutil/trace.go:171","msg":"trace[1890156530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"1.251559412s","start":"2026-08-16T12:55:49.694213Z","end":"2026-08-16T12:55:50.945773Z","steps":["trace[1890156530] 'range keys from in-memory index tree' (duration: 1.251335527s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.945826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.694198Z","time spent":"1.251620533s","remote":"127.0.0.1:48854","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-08-16T12:55:50.946248Z","caller":"traceutil/trace.go:171","msg":"trace[90428244] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"1.04150855s","start":"2026-08-16T12:55:49.904725Z","end":"2026-08-16T12:55:50.946234Z","steps":["trace[90428244] 'read index received' (duration: 982.120358ms)","trace[90428244] 'applied index is now lower than readState.Index' (duration: 59.387262ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:50.94627Z","caller":"traceutil/trace.go:171","msg":"trace[865985959] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.188014805s","start":"2026-08-16T12:55:49.758237Z","end":"2026-08-16T12:55:50.946252Z","steps":["trace[865985959] 'process raft request' (duration: 1.128709215s)","trace[865985959] 'compare' (duration: 58.831989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:50.946373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.758213Z","time spent":"1.188102507s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:50.946442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041711395s","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-08-16T12:55:50.946477Z","caller":"traceutil/trace.go:171","msg":"trace[1864657365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"1.041747526s","start":"2026-08-16T12:55:49.904719Z","end":"2026-08-16T12:55:50.946467Z","steps":["trace[1864657365] 'agreement among raft nodes before linearized reading' (duration: 1.041606112s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.946508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.904659Z","time spent":"1.041842517s","remote":"127.0.0.1:49096","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-08-16T12:55:50.946558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041541271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:50.946645Z","caller":"traceutil/trace.go:171","msg":"trace[300639563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"1.041657313s","start":"2026-08-16T12:55:49.904968Z","end":"2026-08-16T12:55:50.946625Z","steps":["trace[300639563] 'agreement among raft nodes before linearized reading' (duration: 1.041445138s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.946694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.904959Z","time spent":"1.041723075s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:50.946732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.734934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:50.946755Z","caller":"traceutil/trace.go:171","msg":"trace[17639604] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1224; }","duration":"925.760085ms","start":"2026-08-16T12:55:50.020988Z","end":"2026-08-16T12:55:50.946748Z","steps":["trace[17639604] 'agreement among raft nodes before linearized reading' (duration: 925.709224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.946771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:50.020913Z","time spent":"925.854157ms","remote":"127.0.0.1:39100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:55:50.946855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.604791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:50.94687Z","caller":"traceutil/trace.go:171","msg":"trace[592131473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1224; }","duration":"234.646133ms","start":"2026-08-16T12:55:50.712218Z","end":"2026-08-16T12:55:50.946864Z","steps":["trace[592131473] 'agreement among raft nodes before linearized reading' (duration: 234.621542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.946985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040911987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-16T12:55:50.947001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04196746s","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-08-16T12:55:50.947026Z","caller":"traceutil/trace.go:171","msg":"trace[910362428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"1.040967718s","start":"2026-08-16T12:55:49.906046Z","end":"2026-08-16T12:55:50.947014Z","steps":["trace[910362428] 'agreement among raft nodes before linearized reading' (duration: 1.040858545s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:50.947037Z","caller":"traceutil/trace.go:171","msg":"trace[264064658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"1.042016861s","start":"2026-08-16T12:55:49.905009Z","end":"2026-08-16T12:55:50.947026Z","steps":["trace[264064658] 'agreement among raft nodes before linearized reading' (duration: 1.041917689s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.947065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.906039Z","time spent":"1.041019199s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:55:50.947137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041778517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:55:50.94718Z","caller":"traceutil/trace.go:171","msg":"trace[987264947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"1.041827467s","start":"2026-08-16T12:55:49.905327Z","end":"2026-08-16T12:55:50.947155Z","steps":["trace[987264947] 'agreement among raft nodes before linearized reading' (duration: 1.041756535s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.946989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.512356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:55:50.947205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.905315Z","time spent":"1.041884419s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:55:50.947237Z","caller":"traceutil/trace.go:171","msg":"trace[704822917] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1224; }","duration":"591.784082ms","start":"2026-08-16T12:55:50.355437Z","end":"2026-08-16T12:55:50.947222Z","steps":["trace[704822917] 'agreement among raft nodes before linearized reading' (duration: 591.517806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:50.947275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:50.355422Z","time spent":"591.838023ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:55:50.947067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:49.905004Z","time spent":"1.042056213s","remote":"127.0.0.1:49096","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-08-16T12:55:53.203447Z","caller":"traceutil/trace.go:171","msg":"trace[1135696532] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"206.757926ms","start":"2026-08-16T12:55:52.996667Z","end":"2026-08-16T12:55:53.203425Z","steps":["trace[1135696532] 'read index received' (duration: 206.608033ms)","trace[1135696532] 'applied index is now lower than readState.Index' (duration: 148.803µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:53.203525Z","caller":"traceutil/trace.go:171","msg":"trace[2061379563] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"251.349592ms","start":"2026-08-16T12:55:52.95215Z","end":"2026-08-16T12:55:53.2035Z","steps":["trace[2061379563] 'process raft request' (duration: 251.122416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.203663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.981752ms","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-08-16T12:55:53.203702Z","caller":"traceutil/trace.go:171","msg":"trace[216529688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"207.036394ms","start":"2026-08-16T12:55:52.996657Z","end":"2026-08-16T12:55:53.203693Z","steps":["trace[216529688] 'agreement among raft nodes before linearized reading' (duration: 206.88169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.704365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123801193146493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:55:54.039387Z","caller":"traceutil/trace.go:171","msg":"trace[1279238372] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.016824568s","start":"2026-08-16T12:55:53.022533Z","end":"2026-08-16T12:55:54.039358Z","steps":["trace[1279238372] 'process raft request' (duration: 1.016630934s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:54.039549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.02252Z","time spent":"1.01694948s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:54.08438Z","caller":"traceutil/trace.go:171","msg":"trace[992555720] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"880.851353ms","start":"2026-08-16T12:55:53.203514Z","end":"2026-08-16T12:55:54.084365Z","steps":["trace[992555720] 'read index received' (duration: 835.697485ms)","trace[992555720] 'applied index is now lower than readState.Index' (duration: 45.153248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:54.084534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074557162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:55:54.084558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07447654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-16T12:55:54.084558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074482641s","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-08-16T12:55:54.084613Z","caller":"traceutil/trace.go:171","msg":"trace[2146827869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.074560973s","start":"2026-08-16T12:55:53.01004Z","end":"2026-08-16T12:55:54.084601Z","steps":["trace[2146827869] 'agreement among raft nodes before linearized reading' (duration: 1.074397339s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:54.084642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.010033Z","time spent":"1.074605254s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:55:54.084636Z","caller":"traceutil/trace.go:171","msg":"trace[2056326145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"1.074601543s","start":"2026-08-16T12:55:53.010018Z","end":"2026-08-16T12:55:54.084619Z","steps":["trace[2056326145] 'agreement among raft nodes before linearized reading' (duration: 1.074366678s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:54.084566Z","caller":"traceutil/trace.go:171","msg":"trace[1130973500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.074625534s","start":"2026-08-16T12:55:53.009932Z","end":"2026-08-16T12:55:54.084557Z","steps":["trace[1130973500] 'agreement among raft nodes before linearized reading' (duration: 1.074489481s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:54.08468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.009999Z","time spent":"1.074670304s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:55:54.084727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.009916Z","time spent":"1.074787287s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:54.084534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074510561s","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-08-16T12:55:54.084794Z","caller":"traceutil/trace.go:171","msg":"trace[934574638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.074773637s","start":"2026-08-16T12:55:53.010009Z","end":"2026-08-16T12:55:54.084783Z","steps":["trace[934574638] 'agreement among raft nodes before linearized reading' (duration: 1.0744569s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:54.084835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.00996Z","time spent":"1.074863329s","remote":"127.0.0.1:49096","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-08-16T12:55:54.084882Z","caller":"traceutil/trace.go:171","msg":"trace[1711446005] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"878.570751ms","start":"2026-08-16T12:55:53.206302Z","end":"2026-08-16T12:55:54.084872Z","steps":["trace[1711446005] 'process raft request' (duration: 877.980658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:54.084937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:53.206282Z","time spent":"878.616572ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:55.96239Z","caller":"traceutil/trace.go:171","msg":"trace[2029213272] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"482.686628ms","start":"2026-08-16T12:55:55.479675Z","end":"2026-08-16T12:55:55.962362Z","steps":["trace[2029213272] 'read index received' (duration: 482.484433ms)","trace[2029213272] 'applied index is now lower than readState.Index' (duration: 201.025µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:55.962481Z","caller":"traceutil/trace.go:171","msg":"trace[969046335] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"755.002267ms","start":"2026-08-16T12:55:55.207449Z","end":"2026-08-16T12:55:55.962451Z","steps":["trace[969046335] 'process raft request' (duration: 754.761182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.962566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.146989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:55.96261Z","caller":"traceutil/trace.go:171","msg":"trace[951546989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"266.245851ms","start":"2026-08-16T12:55:55.696354Z","end":"2026-08-16T12:55:55.9626Z","steps":["trace[951546989] 'agreement among raft nodes before linearized reading' (duration: 266.160139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.962646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:55.207421Z","time spent":"755.14381ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:55.962716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.286335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-16T12:55:55.962811Z","caller":"traceutil/trace.go:171","msg":"trace[1954635565] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1237; }","duration":"246.404328ms","start":"2026-08-16T12:55:55.716383Z","end":"2026-08-16T12:55:55.962787Z","steps":["trace[1954635565] 'agreement among raft nodes before linearized reading' (duration: 246.156213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.96327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.591889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:55:55.963333Z","caller":"traceutil/trace.go:171","msg":"trace[1481688432] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1237; }","duration":"483.692791ms","start":"2026-08-16T12:55:55.479621Z","end":"2026-08-16T12:55:55.963314Z","steps":["trace[1481688432] 'agreement among raft nodes before linearized reading' (duration: 482.854561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.963379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:55.4796Z","time spent":"483.764043ms","remote":"127.0.0.1:49314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:55:56.280127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.228542ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-16T12:55:56.280233Z","caller":"traceutil/trace.go:171","msg":"trace[632654478] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"315.496562ms","start":"2026-08-16T12:55:55.964711Z","end":"2026-08-16T12:55:56.280207Z","steps":["trace[632654478] 'read index received' (duration: 175.144667ms)","trace[632654478] 'applied index is now lower than readState.Index' (duration: 140.350255ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:56.280327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:55.963897Z","time spent":"316.426114ms","remote":"127.0.0.1:48866","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-16T12:55:56.280392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.700067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:56.280459Z","caller":"traceutil/trace.go:171","msg":"trace[2071864013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"315.776178ms","start":"2026-08-16T12:55:55.964667Z","end":"2026-08-16T12:55:56.280443Z","steps":["trace[2071864013] 'agreement among raft nodes before linearized reading' (duration: 315.662606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:56.2805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:55.964623Z","time spent":"315.868901ms","remote":"127.0.0.1:48840","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-08-16T12:55:56.280534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.53782ms","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-08-16T12:55:56.28062Z","caller":"traceutil/trace.go:171","msg":"trace[330501714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"191.687134ms","start":"2026-08-16T12:55:56.088911Z","end":"2026-08-16T12:55:56.280598Z","steps":["trace[330501714] 'agreement among raft nodes before linearized reading' (duration: 191.429938ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:56.750257Z","caller":"traceutil/trace.go:171","msg":"trace[435905799] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"463.839199ms","start":"2026-08-16T12:55:56.286384Z","end":"2026-08-16T12:55:56.750224Z","steps":["trace[435905799] 'process raft request' (duration: 463.49432ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:56.750292Z","caller":"traceutil/trace.go:171","msg":"trace[1318841430] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"461.109517ms","start":"2026-08-16T12:55:56.289117Z","end":"2026-08-16T12:55:56.750227Z","steps":["trace[1318841430] 'process raft request' (duration: 460.993295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:56.750576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.475168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-16T12:55:56.750626Z","caller":"traceutil/trace.go:171","msg":"trace[1780934972] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1243; }","duration":"462.5593ms","start":"2026-08-16T12:55:56.288056Z","end":"2026-08-16T12:55:56.750616Z","steps":["trace[1780934972] 'agreement among raft nodes before linearized reading' (duration: 462.429647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:56.750679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:56.288047Z","time spent":"462.622921ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-16T12:55:56.750692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:56.289096Z","time spent":"461.420725ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:56.750758Z","caller":"traceutil/trace.go:171","msg":"trace[1952772638] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"461.544567ms","start":"2026-08-16T12:55:56.289201Z","end":"2026-08-16T12:55:56.750745Z","steps":["trace[1952772638] 'process raft request' (duration: 460.983314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:56.750839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:56.289155Z","time spent":"461.652789ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:56.750437Z","caller":"traceutil/trace.go:171","msg":"trace[872303954] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1291; }","duration":"462.328774ms","start":"2026-08-16T12:55:56.288082Z","end":"2026-08-16T12:55:56.750411Z","steps":["trace[872303954] 'read index received' (duration: 4.510402ms)","trace[872303954] 'applied index is now lower than readState.Index' (duration: 457.817232ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:56.750451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:56.286374Z","time spent":"463.981363ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:58.284021Z","caller":"traceutil/trace.go:171","msg":"trace[1796167582] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"316.115117ms","start":"2026-08-16T12:55:57.967879Z","end":"2026-08-16T12:55:58.283994Z","steps":["trace[1796167582] 'process raft request' (duration: 315.947743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:58.284162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:57.967858Z","time spent":"316.233239ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:59.453852Z","caller":"traceutil/trace.go:171","msg":"trace[658303078] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"114.686531ms","start":"2026-08-16T12:55:59.339149Z","end":"2026-08-16T12:55:59.453835Z","steps":["trace[658303078] 'process raft request' (duration: 112.45083ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:00.904581Z","caller":"traceutil/trace.go:171","msg":"trace[1594923523] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1314; }","duration":"131.018973ms","start":"2026-08-16T12:56:00.773528Z","end":"2026-08-16T12:56:00.904547Z","steps":["trace[1594923523] 'read index received' (duration: 79.101221ms)","trace[1594923523] 'applied index is now lower than readState.Index' (duration: 51.915932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:56:00.904855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.264149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:56:00.904912Z","caller":"traceutil/trace.go:171","msg":"trace[867518029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"131.384391ms","start":"2026-08-16T12:56:00.773513Z","end":"2026-08-16T12:56:00.904897Z","steps":["trace[867518029] 'agreement among raft nodes before linearized reading' (duration: 131.147036ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:06.563529Z","caller":"traceutil/trace.go:171","msg":"trace[2066261424] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"112.726517ms","start":"2026-08-16T12:56:06.450754Z","end":"2026-08-16T12:56:06.56348Z","steps":["trace[2066261424] 'process raft request' (duration: 112.49181ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:06.958052Z","caller":"traceutil/trace.go:171","msg":"trace[814281976] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"261.945952ms","start":"2026-08-16T12:56:06.696081Z","end":"2026-08-16T12:56:06.958027Z","steps":["trace[814281976] 'read index received' (duration: 256.344605ms)","trace[814281976] 'applied index is now lower than readState.Index' (duration: 5.600477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:56:06.958398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.305971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:56:06.958332Z","caller":"traceutil/trace.go:171","msg":"trace[1811209668] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"390.189303ms","start":"2026-08-16T12:56:06.568029Z","end":"2026-08-16T12:56:06.958219Z","steps":["trace[1811209668] 'process raft request' (duration: 384.447972ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:06.958476Z","caller":"traceutil/trace.go:171","msg":"trace[1222277102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"262.409064ms","start":"2026-08-16T12:56:06.696048Z","end":"2026-08-16T12:56:06.958457Z","steps":["trace[1222277102] 'agreement among raft nodes before linearized reading' (duration: 262.304112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:06.958566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:56:06.567988Z","time spent":"390.491479ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:56:09.789012Z","caller":"traceutil/trace.go:171","msg":"trace[1828141669] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"270.453907ms","start":"2026-08-16T12:56:09.518534Z","end":"2026-08-16T12:56:09.788988Z","steps":["trace[1828141669] 'process raft request' (duration: 270.288013ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:09.921003Z","caller":"traceutil/trace.go:171","msg":"trace[1236266733] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"114.168209ms","start":"2026-08-16T12:56:09.806812Z","end":"2026-08-16T12:56:09.92098Z","steps":["trace[1236266733] 'read index received' (duration: 113.999116ms)","trace[1236266733] 'applied index is now lower than readState.Index' (duration: 168.113µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:09.921047Z","caller":"traceutil/trace.go:171","msg":"trace[1158131591] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"318.09191ms","start":"2026-08-16T12:56:09.602929Z","end":"2026-08-16T12:56:09.921021Z","steps":["trace[1158131591] 'process raft request' (duration: 317.903616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:09.921131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.302532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:56:09.921163Z","caller":"traceutil/trace.go:171","msg":"trace[1339924775] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1325; }","duration":"114.392924ms","start":"2026-08-16T12:56:09.806761Z","end":"2026-08-16T12:56:09.921154Z","steps":["trace[1339924775] 'agreement among raft nodes before linearized reading' (duration: 114.306082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:09.921301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:56:09.602898Z","time spent":"318.205663ms","remote":"127.0.0.1:49096","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-08-16T12:56:11.403003Z","caller":"traceutil/trace.go:171","msg":"trace[1652415878] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"209.755425ms","start":"2026-08-16T12:56:11.193213Z","end":"2026-08-16T12:56:11.402968Z","steps":["trace[1652415878] 'process raft request' (duration: 132.237951ms)","trace[1652415878] 'compare' (duration: 77.372701ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:11.476378Z","caller":"traceutil/trace.go:171","msg":"trace[879244596] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"282.796469ms","start":"2026-08-16T12:56:11.193543Z","end":"2026-08-16T12:56:11.476339Z","steps":["trace[879244596] 'process raft request' (duration: 282.577244ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:11.476459Z","caller":"traceutil/trace.go:171","msg":"trace[1172574700] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"278.639653ms","start":"2026-08-16T12:56:11.197782Z","end":"2026-08-16T12:56:11.476421Z","steps":["trace[1172574700] 'process raft request' (duration: 278.47225ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:11.548956Z","caller":"traceutil/trace.go:171","msg":"trace[1610487951] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1401; }","duration":"293.708506ms","start":"2026-08-16T12:56:11.255224Z","end":"2026-08-16T12:56:11.548932Z","steps":["trace[1610487951] 'read index received' (duration: 70.31006ms)","trace[1610487951] 'applied index is now lower than readState.Index' (duration: 223.397276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:56:11.549158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.91174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-16T12:56:11.549228Z","caller":"traceutil/trace.go:171","msg":"trace[1257871914] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"294.030573ms","start":"2026-08-16T12:56:11.255185Z","end":"2026-08-16T12:56:11.549216Z","steps":["trace[1257871914] 'agreement among raft nodes before linearized reading' (duration: 293.841839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:12.350273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.435723ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:56:12.350413Z","caller":"traceutil/trace.go:171","msg":"trace[561242547] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1350; }","duration":"366.588516ms","start":"2026-08-16T12:56:11.983803Z","end":"2026-08-16T12:56:12.350391Z","steps":["trace[561242547] 'range keys from in-memory index tree' (duration: 366.418711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:12.350768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.76705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:56:12.350919Z","caller":"traceutil/trace.go:171","msg":"trace[588908483] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"756.900671ms","start":"2026-08-16T12:56:11.593968Z","end":"2026-08-16T12:56:12.350869Z","steps":["trace[588908483] 'read index received' (duration: 121.977897ms)","trace[588908483] 'applied index is now lower than readState.Index' (duration: 634.921804ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:12.351033Z","caller":"traceutil/trace.go:171","msg":"trace[995709314] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"791.12453ms","start":"2026-08-16T12:56:11.559895Z","end":"2026-08-16T12:56:12.35102Z","steps":["trace[995709314] 'process raft request' (duration: 156.014482ms)","trace[995709314] 'compare' (duration: 634.433762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:56:12.351431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.451184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:56:12.35148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.568099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:56:12.351497Z","caller":"traceutil/trace.go:171","msg":"trace[871949653] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1351; }","duration":"757.562506ms","start":"2026-08-16T12:56:11.593917Z","end":"2026-08-16T12:56:12.351479Z","steps":["trace[871949653] 'agreement among raft nodes before linearized reading' (duration: 757.446274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:12.351408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.278775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:56:12.351536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:56:11.593897Z","time spent":"757.629867ms","remote":"127.0.0.1:49330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:56:12.351528Z","caller":"traceutil/trace.go:171","msg":"trace[855775927] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1351; }","duration":"255.62686ms","start":"2026-08-16T12:56:12.095887Z","end":"2026-08-16T12:56:12.351514Z","steps":["trace[855775927] 'agreement among raft nodes before linearized reading' (duration: 255.527458ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:12.351613Z","caller":"traceutil/trace.go:171","msg":"trace[1827408655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"654.49633ms","start":"2026-08-16T12:56:11.697069Z","end":"2026-08-16T12:56:12.351565Z","steps":["trace[1827408655] 'agreement among raft nodes before linearized reading' (duration: 654.249765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:12.351101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:56:11.559871Z","time spent":"791.195692ms","remote":"127.0.0.1:49280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:56:12.351752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:56:11.697053Z","time spent":"654.685454ms","remote":"127.0.0.1:48854","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-08-16T12:58:17.997996Z","caller":"traceutil/trace.go:171","msg":"trace[1426556893] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"113.112339ms","start":"2026-08-16T12:58:17.884845Z","end":"2026-08-16T12:58:17.997957Z","steps":["trace[1426556893] 'process raft request' (duration: 112.828023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:27.867139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.479051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:58:27.867212Z","caller":"traceutil/trace.go:171","msg":"trace[1143047832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1974; }","duration":"170.572973ms","start":"2026-08-16T12:58:27.696623Z","end":"2026-08-16T12:58:27.867196Z","steps":["trace[1143047832] 'range keys from in-memory index tree' (duration: 170.401019ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:58:57.181259Z","caller":"traceutil/trace.go:171","msg":"trace[547924746] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"219.78738ms","start":"2026-08-16T12:58:56.961442Z","end":"2026-08-16T12:58:57.181229Z","steps":["trace[547924746] 'process raft request' (duration: 219.585806ms)"],"step_count":1}