{"level":"warn","ts":"2026-08-19T09:01:28.018939Z","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-19T09:01:28.019032Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-19T09:01:28.019191Z","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-19T09:01:28.019208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-19T09:01:28.019265Z","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-19T09:01:28.019925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-08-19T09:01:28.019999Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-19T09:01:28.024031Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.791852ms"} {"level":"info","ts":"2026-08-19T09:01:28.036255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-08-19T09:01:28.036321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:01:28.036336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-08-19T09:01:28.036344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:01:28.036351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-08-19T09:01:28.036384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-08-19T09:01:28.046909Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:01:28.049176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:01:28.050931Z","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-19T09:01:28.054366Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:01:28.05464Z","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-19T09:01:28.05562Z","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-19T09:01:28.055652Z","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-19T09:01:28.054705Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:01:28.056089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-08-19T09:01:28.056232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-19T09:01:28.060393Z","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-19T09:01:28.060615Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-19T09:01:28.060653Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-19T09:01:28.060987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:01:28.061059Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:01:28.237337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:01:28.237438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:01:28.237487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-08-19T09:01:28.23751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:01:28.237522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-19T09:01:28.237539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-08-19T09:01:28.237554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-19T09:01:28.242103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:01:28.242427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:01:28.242436Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:01:28.242555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:01:28.243146Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:01:28.243707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:01:28.244212Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:01:28.244396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:01:28.244442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:01:28.246384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-08-19T09:01:28.249865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-19T09:01:37.934802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.153726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-19T09:01:37.93492Z","caller":"traceutil/trace.go:171","msg":"trace[2043417083] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:340; }","duration":"136.346379ms","start":"2026-08-19T09:01:37.798556Z","end":"2026-08-19T09:01:37.934903Z","steps":["trace[2043417083] 'range keys from in-memory index tree' (duration: 136.037635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.474121ms","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-19T09:01:37.93526Z","caller":"traceutil/trace.go:171","msg":"trace[426744589] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:340; }","duration":"636.550482ms","start":"2026-08-19T09:01:37.298699Z","end":"2026-08-19T09:01:37.935249Z","steps":["trace[426744589] 'range keys from in-memory index tree' (duration: 636.361428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.29867Z","time spent":"636.611643ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-08-19T09:01:37.935442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.954764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-19T09:01:37.935476Z","caller":"traceutil/trace.go:171","msg":"trace[1561654210] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:340; }","duration":"337.029316ms","start":"2026-08-19T09:01:37.598438Z","end":"2026-08-19T09:01:37.935467Z","steps":["trace[1561654210] 'range keys from in-memory index tree' (duration: 336.868413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.713077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-08-19T09:01:37.93548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.803541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-19T09:01:37.935569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.02328ms","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-19T09:01:37.935649Z","caller":"traceutil/trace.go:171","msg":"trace[304414653] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:340; }","duration":"587.204863ms","start":"2026-08-19T09:01:37.348427Z","end":"2026-08-19T09:01:37.935632Z","steps":["trace[304414653] 'range keys from in-memory index tree' (duration: 586.915308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.456188ms","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-19T09:01:37.935631Z","caller":"traceutil/trace.go:171","msg":"trace[1186040161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:340; }","duration":"386.974863ms","start":"2026-08-19T09:01:37.548604Z","end":"2026-08-19T09:01:37.935579Z","steps":["trace[1186040161] 'range keys from in-memory index tree' (duration: 386.666839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.348402Z","time spent":"587.283484ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-08-19T09:01:37.935684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.117168ms","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-19T09:01:37.935552Z","caller":"traceutil/trace.go:171","msg":"trace[793288677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"486.842699ms","start":"2026-08-19T09:01:37.448694Z","end":"2026-08-19T09:01:37.935537Z","steps":["trace[793288677] 'range keys from in-memory index tree' (duration: 486.569035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.019992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-08-19T09:01:37.935722Z","caller":"traceutil/trace.go:171","msg":"trace[1322962610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:340; }","duration":"236.557769ms","start":"2026-08-19T09:01:37.699145Z","end":"2026-08-19T09:01:37.935703Z","steps":["trace[1322962610] 'range keys from in-memory index tree' (duration: 236.296245ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:37.935742Z","caller":"traceutil/trace.go:171","msg":"trace[706638191] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:340; }","duration":"287.25026ms","start":"2026-08-19T09:01:37.648479Z","end":"2026-08-19T09:01:37.935729Z","steps":["trace[706638191] 'range keys from in-memory index tree' (duration: 286.999226ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:37.935749Z","caller":"traceutil/trace.go:171","msg":"trace[1486292357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:340; }","duration":"186.232886ms","start":"2026-08-19T09:01:37.749509Z","end":"2026-08-19T09:01:37.935742Z","steps":["trace[1486292357] 'range keys from in-memory index tree' (duration: 185.91195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.548583Z","time spent":"387.142977ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-08-19T09:01:37.935868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.467638ms","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-19T09:01:37.936067Z","caller":"traceutil/trace.go:171","msg":"trace[1864947963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:340; }","duration":"538.09257ms","start":"2026-08-19T09:01:37.397954Z","end":"2026-08-19T09:01:37.936046Z","steps":["trace[1864947963] 'range keys from in-memory index tree' (duration: 537.253965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.935913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.789989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-08-19T09:01:37.936146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.39794Z","time spent":"538.189911ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-08-19T09:01:37.935503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.598418Z","time spent":"337.078536ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-08-19T09:01:37.936159Z","caller":"traceutil/trace.go:171","msg":"trace[1898909077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:340; }","duration":"437.136455ms","start":"2026-08-19T09:01:37.499003Z","end":"2026-08-19T09:01:37.936139Z","steps":["trace[1898909077] 'range keys from in-memory index tree' (duration: 436.327092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:37.936632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.498981Z","time spent":"437.629903ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-08-19T09:01:37.935925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.448675Z","time spent":"487.069423ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-08-19T09:01:43.246639Z","caller":"traceutil/trace.go:171","msg":"trace[566061555] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"153.970229ms","start":"2026-08-19T09:01:43.092639Z","end":"2026-08-19T09:01:43.246609Z","steps":["trace[566061555] 'process raft request' (duration: 153.787816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.044067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900616586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:01:44.544307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900616586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:01:44.865743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.541535923s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:01:44.866191Z","caller":"traceutil/trace.go:171","msg":"trace[133363086] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.542111112s","start":"2026-08-19T09:01:43.324052Z","end":"2026-08-19T09:01:44.866163Z","steps":["trace[133363086] 'process raft request' (duration: 1.541954289s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.866778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:43.324026Z","time spent":"1.542218583s","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:44.870366Z","caller":"traceutil/trace.go:171","msg":"trace[1067772868] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"1.32719679s","start":"2026-08-19T09:01:43.543158Z","end":"2026-08-19T09:01:44.870355Z","steps":["trace[1067772868] 'read index received' (duration: 1.322856412s)","trace[1067772868] 'applied index is now lower than readState.Index' (duration: 4.339728ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:01:44.870423Z","caller":"traceutil/trace.go:171","msg":"trace[1952858516] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.369865368s","start":"2026-08-19T09:01:43.500552Z","end":"2026-08-19T09:01:44.870417Z","steps":["trace[1952858516] 'process raft request' (duration: 1.369749786s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.870467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:43.500521Z","time spent":"1.369917998s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:01:44.870525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.927223ms","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-19T09:01:44.870594Z","caller":"traceutil/trace.go:171","msg":"trace[1061619907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"850.036425ms","start":"2026-08-19T09:01:44.020541Z","end":"2026-08-19T09:01:44.870577Z","steps":["trace[1061619907] 'agreement among raft nodes before linearized reading' (duration: 849.919033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.87065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:44.020521Z","time spent":"850.108326ms","remote":"127.0.0.1:52114","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-19T09:01:44.870705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327550475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:01:44.870762Z","caller":"traceutil/trace.go:171","msg":"trace[953079014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"1.327639477s","start":"2026-08-19T09:01:43.543104Z","end":"2026-08-19T09:01:44.870743Z","steps":["trace[953079014] 'agreement among raft nodes before linearized reading' (duration: 1.327561355s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.870761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.518543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-19T09:01:44.870782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:43.543082Z","time spent":"1.327695407s","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:01:44.870806Z","caller":"traceutil/trace.go:171","msg":"trace[296029231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"337.599973ms","start":"2026-08-19T09:01:44.533192Z","end":"2026-08-19T09:01:44.870792Z","steps":["trace[296029231] 'agreement among raft nodes before linearized reading' (duration: 337.524952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.87088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:44.53317Z","time spent":"337.701886ms","remote":"127.0.0.1:52114","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-19T09:01:44.870878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.663504ms","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-19T09:01:44.870952Z","caller":"traceutil/trace.go:171","msg":"trace[75152342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"337.780657ms","start":"2026-08-19T09:01:44.533151Z","end":"2026-08-19T09:01:44.870932Z","steps":["trace[75152342] 'agreement among raft nodes before linearized reading' (duration: 337.598834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:44.870991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:44.533133Z","time spent":"337.850317ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:01:55.490702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900616662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:01:55.990893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900616662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:01:56.49185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900616662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:01:56.727685Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.771559124s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:01:56.728046Z","caller":"traceutil/trace.go:171","msg":"trace[365401004] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.772046411s","start":"2026-08-19T09:01:54.955982Z","end":"2026-08-19T09:01:56.728028Z","steps":["trace[365401004] 'process raft request' (duration: 1.771913679s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.728488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:54.955959Z","time spent":"1.772404126s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:56.886656Z","caller":"traceutil/trace.go:171","msg":"trace[1100398311] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"1.721670132s","start":"2026-08-19T09:01:55.164953Z","end":"2026-08-19T09:01:56.886623Z","steps":["trace[1100398311] 'process raft request' (duration: 1.705613532s)","trace[1100398311] 'compare' (duration: 15.887758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:56.886814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:55.164905Z","time spent":"1.721825904s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:56.897949Z","caller":"traceutil/trace.go:171","msg":"trace[624258165] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:452; }","duration":"1.907809107s","start":"2026-08-19T09:01:54.990102Z","end":"2026-08-19T09:01:56.897911Z","steps":["trace[624258165] 'read index received' (duration: 1.737779593s)","trace[624258165] 'applied index is now lower than readState.Index' (duration: 170.028354ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:56.898074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9079657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-19T09:01:56.898231Z","caller":"traceutil/trace.go:171","msg":"trace[2006089332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:438; }","duration":"1.908167302s","start":"2026-08-19T09:01:54.99005Z","end":"2026-08-19T09:01:56.898217Z","steps":["trace[2006089332] 'agreement among raft nodes before linearized reading' (duration: 1.907949709s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.898086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887744693s","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-19T09:01:56.898302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:54.990027Z","time spent":"1.908265813s","remote":"127.0.0.1:52114","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-19T09:01:56.898326Z","caller":"traceutil/trace.go:171","msg":"trace[1927131230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:438; }","duration":"1.888032109s","start":"2026-08-19T09:01:55.010284Z","end":"2026-08-19T09:01:56.898316Z","steps":["trace[1927131230] 'agreement among raft nodes before linearized reading' (duration: 1.887760654s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.898351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:55.010262Z","time spent":"1.888082009s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:01:56.898135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169134856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:01:56.898404Z","caller":"traceutil/trace.go:171","msg":"trace[1679012676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"1.169444062s","start":"2026-08-19T09:01:55.728954Z","end":"2026-08-19T09:01:56.898398Z","steps":["trace[1679012676] 'agreement among raft nodes before linearized reading' (duration: 1.169155077s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.898423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:55.728933Z","time spent":"1.169485932s","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:01:56.898173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.774124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:01:56.898509Z","caller":"traceutil/trace.go:171","msg":"trace[948776488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"364.164321ms","start":"2026-08-19T09:01:56.534339Z","end":"2026-08-19T09:01:56.898504Z","steps":["trace[948776488] 'agreement among raft nodes before linearized reading' (duration: 363.805685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.898528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:56.534312Z","time spent":"364.210782ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:01:56.89817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576843878s","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-19T09:01:56.89862Z","caller":"traceutil/trace.go:171","msg":"trace[823930255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:438; }","duration":"1.577341277s","start":"2026-08-19T09:01:55.321272Z","end":"2026-08-19T09:01:56.898613Z","steps":["trace[823930255] 'agreement among raft nodes before linearized reading' (duration: 1.576854099s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:56.898639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:55.321246Z","time spent":"1.577387557s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-19T09:02:03.195568Z","caller":"traceutil/trace.go:171","msg":"trace[102044354] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"197.898916ms","start":"2026-08-19T09:02:02.997647Z","end":"2026-08-19T09:02:03.195546Z","steps":["trace[102044354] 'process raft request' (duration: 197.850885ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:03.195858Z","caller":"traceutil/trace.go:171","msg":"trace[325524583] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"198.214162ms","start":"2026-08-19T09:02:02.997585Z","end":"2026-08-19T09:02:03.195799Z","steps":["trace[325524583] 'process raft request' (duration: 197.793605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:03.53732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.680793ms","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-19T09:02:03.53755Z","caller":"traceutil/trace.go:171","msg":"trace[1855366664] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:455; }","duration":"214.909848ms","start":"2026-08-19T09:02:03.322605Z","end":"2026-08-19T09:02:03.537515Z","steps":["trace[1855366664] 'range keys from in-memory index tree' (duration: 214.649963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:13.52993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.485091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-19T09:02:13.530354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.387972ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:02:13.530286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.441852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:02:13.530487Z","caller":"traceutil/trace.go:171","msg":"trace[1199716300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:476; }","duration":"207.496733ms","start":"2026-08-19T09:02:13.322955Z","end":"2026-08-19T09:02:13.530452Z","steps":["trace[1199716300] 'range keys from in-memory index tree' (duration: 207.379822ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:13.530499Z","caller":"traceutil/trace.go:171","msg":"trace[700292409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"257.730246ms","start":"2026-08-19T09:02:13.272759Z","end":"2026-08-19T09:02:13.53049Z","steps":["trace[700292409] 'range keys from in-memory index tree' (duration: 257.33016ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:13.530438Z","caller":"traceutil/trace.go:171","msg":"trace[1849163805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"285.037629ms","start":"2026-08-19T09:02:13.245378Z","end":"2026-08-19T09:02:13.530415Z","steps":["trace[1849163805] 'range keys from in-memory index tree' (duration: 284.312558ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:13.861813Z","caller":"traceutil/trace.go:171","msg":"trace[1515914156] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:496; }","duration":"324.198432ms","start":"2026-08-19T09:02:13.537592Z","end":"2026-08-19T09:02:13.86179Z","steps":["trace[1515914156] 'read index received' (duration: 62.083752ms)","trace[1515914156] 'applied index is now lower than readState.Index' (duration: 262.1137ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:13.861928Z","caller":"traceutil/trace.go:171","msg":"trace[145055198] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"327.333427ms","start":"2026-08-19T09:02:13.534566Z","end":"2026-08-19T09:02:13.861899Z","steps":["trace[145055198] 'process raft request' (duration: 327.080114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:13.861992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.381414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:13.862035Z","caller":"traceutil/trace.go:171","msg":"trace[1395298039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"324.458406ms","start":"2026-08-19T09:02:13.537565Z","end":"2026-08-19T09:02:13.862024Z","steps":["trace[1395298039] 'agreement among raft nodes before linearized reading' (duration: 324.312454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:13.862074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:13.537559Z","time spent":"324.507867ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:02:13.862066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:13.534548Z","time spent":"327.446649ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:38.218386Z","caller":"traceutil/trace.go:171","msg":"trace[692392153] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"134.563723ms","start":"2026-08-19T09:02:38.083795Z","end":"2026-08-19T09:02:38.218358Z","steps":["trace[692392153] 'process raft request' (duration: 117.975666ms)","trace[692392153] 'compare' (duration: 16.432995ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:38.218956Z","caller":"traceutil/trace.go:171","msg":"trace[2047353012] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"115.418611ms","start":"2026-08-19T09:02:38.103518Z","end":"2026-08-19T09:02:38.218937Z","steps":["trace[2047353012] 'process raft request' (duration: 115.115197ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:38.520858Z","caller":"traceutil/trace.go:171","msg":"trace[1456252724] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"159.414344ms","start":"2026-08-19T09:02:38.361427Z","end":"2026-08-19T09:02:38.520841Z","steps":["trace[1456252724] 'read index received' (duration: 151.85233ms)","trace[1456252724] 'applied index is now lower than readState.Index' (duration: 7.561264ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:38.521137Z","caller":"traceutil/trace.go:171","msg":"trace[440251583] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"179.954066ms","start":"2026-08-19T09:02:38.341148Z","end":"2026-08-19T09:02:38.521102Z","steps":["trace[440251583] 'process raft request' (duration: 172.209879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:38.521204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.757349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-08-19T09:02:38.521242Z","caller":"traceutil/trace.go:171","msg":"trace[1706654821] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:694; }","duration":"159.83967ms","start":"2026-08-19T09:02:38.361392Z","end":"2026-08-19T09:02:38.521232Z","steps":["trace[1706654821] 'agreement among raft nodes before linearized reading' (duration: 159.612627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:39.232514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:39.685738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04368699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-08-19T09:02:39.685836Z","caller":"traceutil/trace.go:171","msg":"trace[973471570] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:700; }","duration":"1.043838663s","start":"2026-08-19T09:02:38.641979Z","end":"2026-08-19T09:02:39.685818Z","steps":["trace[973471570] 'range keys from in-memory index tree' (duration: 1.043475588s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:39.685901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:38.641966Z","time spent":"1.043902934s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65458,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-08-19T09:02:39.733402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:40.234533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:40.288617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.597734841s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:02:40.289456Z","caller":"traceutil/trace.go:171","msg":"trace[1673889344] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.599516065s","start":"2026-08-19T09:02:38.689916Z","end":"2026-08-19T09:02:40.289432Z","steps":["trace[1673889344] 'process raft request' (duration: 1.59911943s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.289647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:38.689893Z","time spent":"1.599618776s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:40.41064Z","caller":"traceutil/trace.go:171","msg":"trace[1779595363] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.273416425s","start":"2026-08-19T09:02:39.13719Z","end":"2026-08-19T09:02:40.410607Z","steps":["trace[1779595363] 'process raft request' (duration: 1.268382856s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:40.410695Z","caller":"traceutil/trace.go:171","msg":"trace[1317964713] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"276.080859ms","start":"2026-08-19T09:02:40.134561Z","end":"2026-08-19T09:02:40.410642Z","steps":["trace[1317964713] 'process raft request' (duration: 275.981337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.410783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.137158Z","time spent":"1.273565868s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:02:40.410844Z","caller":"traceutil/trace.go:171","msg":"trace[1953434223] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:728; }","duration":"1.678509972s","start":"2026-08-19T09:02:38.732164Z","end":"2026-08-19T09:02:40.410674Z","steps":["trace[1953434223] 'read index received' (duration: 1.55668988s)","trace[1953434223] 'applied index is now lower than readState.Index' (duration: 121.814742ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:40.411109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678913267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:40.411183Z","caller":"traceutil/trace.go:171","msg":"trace[2036021577] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:705; }","duration":"1.679054349s","start":"2026-08-19T09:02:38.732109Z","end":"2026-08-19T09:02:40.411164Z","steps":["trace[2036021577] 'agreement among raft nodes before linearized reading' (duration: 1.678912457s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:38.73209Z","time spent":"1.679152621s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-19T09:02:40.411389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.584121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-08-19T09:02:40.411475Z","caller":"traceutil/trace.go:171","msg":"trace[1025782705] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:705; }","duration":"708.709353ms","start":"2026-08-19T09:02:39.702745Z","end":"2026-08-19T09:02:40.411454Z","steps":["trace[1025782705] 'agreement among raft nodes before linearized reading' (duration: 708.45121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.515353ms","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-19T09:02:40.41152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.702728Z","time spent":"708.783234ms","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74791,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-08-19T09:02:40.411551Z","caller":"traceutil/trace.go:171","msg":"trace[219052756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"864.570134ms","start":"2026-08-19T09:02:39.546967Z","end":"2026-08-19T09:02:40.411537Z","steps":["trace[219052756] 'agreement among raft nodes before linearized reading' (duration: 864.474912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104177352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-19T09:02:40.411588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.546957Z","time spent":"864.625125ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-19T09:02:40.411608Z","caller":"traceutil/trace.go:171","msg":"trace[1166347493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"1.104263462s","start":"2026-08-19T09:02:39.307325Z","end":"2026-08-19T09:02:40.411589Z","steps":["trace[1166347493] 'agreement among raft nodes before linearized reading' (duration: 1.10409474s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.468642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-19T09:02:40.411651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.307304Z","time spent":"1.104338573s","remote":"127.0.0.1:52114","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-19T09:02:40.411668Z","caller":"traceutil/trace.go:171","msg":"trace[1831699894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"880.572624ms","start":"2026-08-19T09:02:39.531085Z","end":"2026-08-19T09:02:40.411658Z","steps":["trace[1831699894] 'agreement among raft nodes before linearized reading' (duration: 880.440331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.41148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079559605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-08-19T09:02:40.41173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.531062Z","time spent":"880.660395ms","remote":"127.0.0.1:52114","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-19T09:02:40.411791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.593522ms","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-19T09:02:40.411795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.046448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:40.411831Z","caller":"traceutil/trace.go:171","msg":"trace[1775493095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"613.662953ms","start":"2026-08-19T09:02:39.798158Z","end":"2026-08-19T09:02:40.411821Z","steps":["trace[1775493095] 'agreement among raft nodes before linearized reading' (duration: 613.566222ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:40.411834Z","caller":"traceutil/trace.go:171","msg":"trace[1812868890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"875.109769ms","start":"2026-08-19T09:02:39.536716Z","end":"2026-08-19T09:02:40.411826Z","steps":["trace[1812868890] 'agreement among raft nodes before linearized reading' (duration: 875.059659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.798141Z","time spent":"613.712055ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:02:40.411862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.5367Z","time spent":"875.15724ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:02:40.411757Z","caller":"traceutil/trace.go:171","msg":"trace[1887687366] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"1.079894969s","start":"2026-08-19T09:02:39.331849Z","end":"2026-08-19T09:02:40.411744Z","steps":["trace[1887687366] 'agreement among raft nodes before linearized reading' (duration: 1.079533463s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.411906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.33183Z","time spent":"1.080065942s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-08-19T09:02:40.411986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.638739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-08-19T09:02:40.412021Z","caller":"traceutil/trace.go:171","msg":"trace[1954271745] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"680.69379ms","start":"2026-08-19T09:02:39.731316Z","end":"2026-08-19T09:02:40.41201Z","steps":["trace[1954271745] 'agreement among raft nodes before linearized reading' (duration: 680.611529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.412056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.731304Z","time spent":"680.74241ms","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-08-19T09:02:40.411412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.630147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-08-19T09:02:40.412013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.57698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-08-19T09:02:40.41214Z","caller":"traceutil/trace.go:171","msg":"trace[638059300] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"879.400967ms","start":"2026-08-19T09:02:39.532728Z","end":"2026-08-19T09:02:40.412129Z","steps":["trace[638059300] 'agreement among raft nodes before linearized reading' (duration: 878.561975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.412177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.532707Z","time spent":"879.459538ms","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-08-19T09:02:40.412197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.19061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:40.412224Z","caller":"traceutil/trace.go:171","msg":"trace[818088617] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:705; }","duration":"262.243941ms","start":"2026-08-19T09:02:40.149972Z","end":"2026-08-19T09:02:40.412216Z","steps":["trace[818088617] 'agreement among raft nodes before linearized reading' (duration: 262.199351ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:40.412241Z","caller":"traceutil/trace.go:171","msg":"trace[1019241468] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"479.822354ms","start":"2026-08-19T09:02:39.932322Z","end":"2026-08-19T09:02:40.412145Z","steps":["trace[1019241468] 'agreement among raft nodes before linearized reading' (duration: 479.312616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.412339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:39.932266Z","time spent":"480.058787ms","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-19T09:02:40.412414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.097757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-19T09:02:40.412492Z","caller":"traceutil/trace.go:171","msg":"trace[1777898884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"352.28668ms","start":"2026-08-19T09:02:40.060194Z","end":"2026-08-19T09:02:40.412481Z","steps":["trace[1777898884] 'agreement among raft nodes before linearized reading' (duration: 352.139448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.412534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:40.060171Z","time spent":"352.356481ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:02:40.411425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478576452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:40.412641Z","caller":"traceutil/trace.go:171","msg":"trace[1432478695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:705; }","duration":"1.480078623s","start":"2026-08-19T09:02:38.932544Z","end":"2026-08-19T09:02:40.412623Z","steps":["trace[1432478695] 'agreement among raft nodes before linearized reading' (duration: 1.478538021s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:40.41269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:38.932526Z","time spent":"1.480149975s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-19T09:02:41.278039Z","caller":"traceutil/trace.go:171","msg":"trace[1720265836] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"107.08611ms","start":"2026-08-19T09:02:41.170925Z","end":"2026-08-19T09:02:41.278011Z","steps":["trace[1720265836] 'process raft request' (duration: 76.665375ms)","trace[1720265836] 'compare' (duration: 30.162812ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:41.324905Z","caller":"traceutil/trace.go:171","msg":"trace[1590160206] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"127.579808ms","start":"2026-08-19T09:02:41.197309Z","end":"2026-08-19T09:02:41.324888Z","steps":["trace[1590160206] 'process raft request' (duration: 127.359656ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:41.601724Z","caller":"traceutil/trace.go:171","msg":"trace[935063433] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"109.500963ms","start":"2026-08-19T09:02:41.492185Z","end":"2026-08-19T09:02:41.601686Z","steps":["trace[935063433] 'read index received' (duration: 90.89421ms)","trace[935063433] 'applied index is now lower than readState.Index' (duration: 18.605763ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:41.601916Z","caller":"traceutil/trace.go:171","msg":"trace[874999500] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"131.209148ms","start":"2026-08-19T09:02:41.470689Z","end":"2026-08-19T09:02:41.601898Z","steps":["trace[874999500] 'process raft request' (duration: 112.513764ms)","trace[874999500] 'compare' (duration: 18.264688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:41.602084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.869788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T09:02:41.602192Z","caller":"traceutil/trace.go:171","msg":"trace[779504468] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:727; }","duration":"110.012379ms","start":"2026-08-19T09:02:41.492162Z","end":"2026-08-19T09:02:41.602174Z","steps":["trace[779504468] 'agreement among raft nodes before linearized reading' (duration: 109.718235ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:45.694084Z","caller":"traceutil/trace.go:171","msg":"trace[1050661407] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"156.514961ms","start":"2026-08-19T09:02:45.53754Z","end":"2026-08-19T09:02:45.694055Z","steps":["trace[1050661407] 'read index received' (duration: 155.857762ms)","trace[1050661407] 'applied index is now lower than readState.Index' (duration: 656.079µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:45.694193Z","caller":"traceutil/trace.go:171","msg":"trace[528669190] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"280.863106ms","start":"2026-08-19T09:02:45.413273Z","end":"2026-08-19T09:02:45.694136Z","steps":["trace[528669190] 'process raft request' (duration: 280.187357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:45.987075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.496001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:45.987164Z","caller":"traceutil/trace.go:171","msg":"trace[378321451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"449.646314ms","start":"2026-08-19T09:02:45.537495Z","end":"2026-08-19T09:02:45.987141Z","steps":["trace[378321451] 'agreement among raft nodes before linearized reading' (duration: 156.674313ms)","trace[378321451] 'range keys from in-memory index tree' (duration: 292.800878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:45.987211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:45.537472Z","time spent":"449.730024ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:02:45.987347Z","caller":"traceutil/trace.go:171","msg":"trace[4348409] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"213.25234ms","start":"2026-08-19T09:02:45.774061Z","end":"2026-08-19T09:02:45.987314Z","steps":["trace[4348409] 'process raft request' (duration: 158.629699ms)","trace[4348409] 'compare' (duration: 54.480439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:46.936006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:47.436824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:47.516796Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109590924s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:02:47.523624Z","caller":"traceutil/trace.go:171","msg":"trace[1951311360] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.117657182s","start":"2026-08-19T09:02:46.405936Z","end":"2026-08-19T09:02:47.523593Z","steps":["trace[1951311360] 'process raft request' (duration: 1.111759692s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.523743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.405909Z","time spent":"1.117770843s","remote":"127.0.0.1:51948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:47.548336Z","caller":"traceutil/trace.go:171","msg":"trace[359876538] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"1.112804076s","start":"2026-08-19T09:02:46.435489Z","end":"2026-08-19T09:02:47.548293Z","steps":["trace[359876538] 'read index received' (duration: 1.081554044s)","trace[359876538] 'applied index is now lower than readState.Index' (duration: 31.248922ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:47.548666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005591656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T09:02:47.548734Z","caller":"traceutil/trace.go:171","msg":"trace[989556397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"1.005689467s","start":"2026-08-19T09:02:46.543027Z","end":"2026-08-19T09:02:47.548716Z","steps":["trace[989556397] 'agreement among raft nodes before linearized reading' (duration: 1.005515804s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.548764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.543012Z","time spent":"1.005745697s","remote":"127.0.0.1:52114","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-19T09:02:47.548936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00380827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-19T09:02:47.548966Z","caller":"traceutil/trace.go:171","msg":"trace[1270483735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"1.003870852s","start":"2026-08-19T09:02:46.545085Z","end":"2026-08-19T09:02:47.548956Z","steps":["trace[1270483735] 'agreement among raft nodes before linearized reading' (duration: 1.003799781s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.548934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.59902ms","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-19T09:02:47.548988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.662135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:02:47.549036Z","caller":"traceutil/trace.go:171","msg":"trace[1197164853] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:755; }","duration":"147.763716ms","start":"2026-08-19T09:02:47.401263Z","end":"2026-08-19T09:02:47.549026Z","steps":["trace[1197164853] 'agreement among raft nodes before linearized reading' (duration: 147.658734ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:47.549048Z","caller":"traceutil/trace.go:171","msg":"trace[1460890306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"995.754372ms","start":"2026-08-19T09:02:46.553269Z","end":"2026-08-19T09:02:47.549023Z","steps":["trace[1460890306] 'agreement among raft nodes before linearized reading' (duration: 995.342506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.548994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.545065Z","time spent":"1.003923012s","remote":"127.0.0.1:52114","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-19T09:02:47.549141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004125045s","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-19T09:02:47.549129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.553248Z","time spent":"995.868693ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-19T09:02:47.549177Z","caller":"traceutil/trace.go:171","msg":"trace[972226842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"1.004173156s","start":"2026-08-19T09:02:46.544997Z","end":"2026-08-19T09:02:47.549171Z","steps":["trace[972226842] 'agreement among raft nodes before linearized reading' (duration: 1.004099645s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.549201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.544991Z","time spent":"1.004205156s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:02:47.549168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113638757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:02:47.549252Z","caller":"traceutil/trace.go:171","msg":"trace[2144643798] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:755; }","duration":"1.113748318s","start":"2026-08-19T09:02:46.435483Z","end":"2026-08-19T09:02:47.549231Z","steps":["trace[2144643798] 'agreement among raft nodes before linearized reading' (duration: 1.113513695s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.549307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.435426Z","time spent":"1.11386893s","remote":"127.0.0.1:52322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:02:47.549383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005561305s","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-19T09:02:47.549543Z","caller":"traceutil/trace.go:171","msg":"trace[1870468210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"1.005765248s","start":"2026-08-19T09:02:46.543751Z","end":"2026-08-19T09:02:47.549517Z","steps":["trace[1870468210] 'agreement among raft nodes before linearized reading' (duration: 1.005151919s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:47.549653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:46.543746Z","time spent":"1.005893631s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:02:49.036822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:02:49.101721Z","caller":"traceutil/trace.go:171","msg":"trace[540325314] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"565.313085ms","start":"2026-08-19T09:02:48.536388Z","end":"2026-08-19T09:02:49.101701Z","steps":["trace[540325314] 'read index received' (duration: 565.161523ms)","trace[540325314] 'applied index is now lower than readState.Index' (duration: 150.662µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:49.101918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.516128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:49.101992Z","caller":"traceutil/trace.go:171","msg":"trace[486746116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"565.62873ms","start":"2026-08-19T09:02:48.536352Z","end":"2026-08-19T09:02:49.10198Z","steps":["trace[486746116] 'agreement among raft nodes before linearized reading' (duration: 565.512138ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:49.10213Z","caller":"traceutil/trace.go:171","msg":"trace[1722393738] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"585.69267ms","start":"2026-08-19T09:02:48.516116Z","end":"2026-08-19T09:02:49.101809Z","steps":["trace[1722393738] 'process raft request' (duration: 585.444726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:49.102162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:48.536335Z","time spent":"565.812432ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:02:49.102513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:48.516097Z","time spent":"586.308258ms","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:02:50.064057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:50.564568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:51.065404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:51.56599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:52.066667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342831900617360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:52.398128Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.834526612s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:02:52.398527Z","caller":"traceutil/trace.go:171","msg":"trace[1419405322] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"2.834998818s","start":"2026-08-19T09:02:49.5635Z","end":"2026-08-19T09:02:52.398499Z","steps":["trace[1419405322] 'process raft request' (duration: 2.834813946s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.398701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:49.563473Z","time spent":"2.83514394s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:52.447404Z","caller":"traceutil/trace.go:171","msg":"trace[1279216398] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"2.88367418s","start":"2026-08-19T09:02:49.5637Z","end":"2026-08-19T09:02:52.447374Z","steps":["trace[1279216398] 'read index received' (duration: 2.834649872s)","trace[1279216398] 'applied index is now lower than readState.Index' (duration: 49.023088ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:52.44758Z","caller":"traceutil/trace.go:171","msg":"trace[369394767] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"774.847933ms","start":"2026-08-19T09:02:51.672702Z","end":"2026-08-19T09:02:52.44755Z","steps":["trace[369394767] 'process raft request' (duration: 764.2658ms)","trace[369394767] 'compare' (duration: 10.286469ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:52.447687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.883963924s","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-19T09:02:52.44773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:51.672679Z","time spent":"774.967175ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:52.447765Z","caller":"traceutil/trace.go:171","msg":"trace[596416775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"2.884085286s","start":"2026-08-19T09:02:49.563665Z","end":"2026-08-19T09:02:52.44775Z","steps":["trace[596416775] 'agreement among raft nodes before linearized reading' (duration: 2.883877604s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.447822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:49.563655Z","time spent":"2.884154987s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:02:52.447886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912905592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:52.447921Z","caller":"traceutil/trace.go:171","msg":"trace[63613473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"1.912960593s","start":"2026-08-19T09:02:50.53495Z","end":"2026-08-19T09:02:52.447911Z","steps":["trace[63613473] 'agreement among raft nodes before linearized reading' (duration: 1.912903863s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.447955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:50.534935Z","time spent":"1.913014024s","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:02:52.44798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.884160867s","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-19T09:02:52.448025Z","caller":"traceutil/trace.go:171","msg":"trace[907203897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"2.884218118s","start":"2026-08-19T09:02:49.563795Z","end":"2026-08-19T09:02:52.448013Z","steps":["trace[907203897] 'agreement among raft nodes before linearized reading' (duration: 2.884108296s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.448026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018092891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-19T09:02:52.448034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.872679193s","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-19T09:02:52.448079Z","caller":"traceutil/trace.go:171","msg":"trace[1363592356] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:773; }","duration":"1.018173632s","start":"2026-08-19T09:02:51.429893Z","end":"2026-08-19T09:02:52.448067Z","steps":["trace[1363592356] 'agreement among raft nodes before linearized reading' (duration: 1.01805002s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.44811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571756223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:02:52.448115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:51.429877Z","time spent":"1.018229702s","remote":"127.0.0.1:51892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-08-19T09:02:52.448144Z","caller":"traceutil/trace.go:171","msg":"trace[601911707] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:773; }","duration":"1.571839203s","start":"2026-08-19T09:02:50.876297Z","end":"2026-08-19T09:02:52.448136Z","steps":["trace[601911707] 'agreement among raft nodes before linearized reading' (duration: 1.571786393s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.448174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:50.87627Z","time spent":"1.571895775s","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:02:52.448106Z","caller":"traceutil/trace.go:171","msg":"trace[1391837936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"2.872753664s","start":"2026-08-19T09:02:49.575336Z","end":"2026-08-19T09:02:52.44809Z","steps":["trace[1391837936] 'agreement among raft nodes before linearized reading' (duration: 2.872542341s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.448246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:49.575317Z","time spent":"2.872916226s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:02:52.448059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:49.563788Z","time spent":"2.884264108s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:02:52.448404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.884592393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-19T09:02:52.44858Z","caller":"traceutil/trace.go:171","msg":"trace[318819382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"2.884790835s","start":"2026-08-19T09:02:49.563766Z","end":"2026-08-19T09:02:52.448556Z","steps":["trace[318819382] 'agreement among raft nodes before linearized reading' (duration: 2.883913953s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:52.448817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:49.563759Z","time spent":"2.885041798s","remote":"127.0.0.1:52114","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-19T09:03:05.098729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.349961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:03:05.098837Z","caller":"traceutil/trace.go:171","msg":"trace[1913970381] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:871; }","duration":"297.508592ms","start":"2026-08-19T09:03:04.801303Z","end":"2026-08-19T09:03:05.098812Z","steps":["trace[1913970381] 'count revisions from in-memory index tree' (duration: 297.253539ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:05.309576Z","caller":"traceutil/trace.go:171","msg":"trace[649816884] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"165.681048ms","start":"2026-08-19T09:03:05.143833Z","end":"2026-08-19T09:03:05.309514Z","steps":["trace[649816884] 'process raft request' (duration: 165.405285ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:10.517577Z","caller":"traceutil/trace.go:171","msg":"trace[1920090469] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"884.174041ms","start":"2026-08-19T09:03:09.633376Z","end":"2026-08-19T09:03:10.51755Z","steps":["trace[1920090469] 'process raft request' (duration: 883.998228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:03:10.517878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:03:09.633354Z","time spent":"884.390974ms","remote":"127.0.0.1:52094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:03:18.456702Z","caller":"traceutil/trace.go:171","msg":"trace[139272785] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"105.08685ms","start":"2026-08-19T09:03:18.3516Z","end":"2026-08-19T09:03:18.456687Z","steps":["trace[139272785] 'process raft request' (duration: 104.984009ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:20.890202Z","caller":"traceutil/trace.go:171","msg":"trace[1904093382] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"152.667407ms","start":"2026-08-19T09:03:20.737508Z","end":"2026-08-19T09:03:20.890176Z","steps":["trace[1904093382] 'process raft request' (duration: 151.394211ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:21.079317Z","caller":"traceutil/trace.go:171","msg":"trace[1122907795] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"179.578576ms","start":"2026-08-19T09:03:20.899714Z","end":"2026-08-19T09:03:21.079293Z","steps":["trace[1122907795] 'process raft request' (duration: 152.144779ms)","trace[1122907795] 'compare' (duration: 27.230315ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:03:21.079583Z","caller":"traceutil/trace.go:171","msg":"trace[171046325] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"178.892398ms","start":"2026-08-19T09:03:20.900683Z","end":"2026-08-19T09:03:21.079575Z","steps":["trace[171046325] 'process raft request' (duration: 178.532553ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:24.402177Z","caller":"traceutil/trace.go:171","msg":"trace[2094887204] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"296.780399ms","start":"2026-08-19T09:03:24.105372Z","end":"2026-08-19T09:03:24.402153Z","steps":["trace[2094887204] 'process raft request' (duration: 296.646154ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:24.520749Z","caller":"traceutil/trace.go:171","msg":"trace[191028978] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"107.546612ms","start":"2026-08-19T09:03:24.413177Z","end":"2026-08-19T09:03:24.520724Z","steps":["trace[191028978] 'process raft request' (duration: 107.47956ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:24.521267Z","caller":"traceutil/trace.go:171","msg":"trace[1535632484] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"110.63143ms","start":"2026-08-19T09:03:24.410621Z","end":"2026-08-19T09:03:24.521253Z","steps":["trace[1535632484] 'process raft request' (duration: 109.924795ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:03:55.509654Z","caller":"traceutil/trace.go:171","msg":"trace[867968032] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"118.843482ms","start":"2026-08-19T09:03:55.390778Z","end":"2026-08-19T09:03:55.509622Z","steps":["trace[867968032] 'process raft request' (duration: 60.301278ms)","trace[867968032] 'compare' (duration: 58.41581ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:09.80274Z","caller":"traceutil/trace.go:171","msg":"trace[78354328] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"141.436789ms","start":"2026-08-19T09:04:09.661274Z","end":"2026-08-19T09:04:09.802711Z","steps":["trace[78354328] 'process raft request' (duration: 141.277335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:16.895587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.452621ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:04:19.27752Z","caller":"traceutil/trace.go:171","msg":"trace[1119954372] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"207.882136ms","start":"2026-08-19T09:04:19.06961Z","end":"2026-08-19T09:04:19.277492Z","steps":["trace[1119954372] 'process raft request' (duration: 207.734172ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:19.59134Z","caller":"traceutil/trace.go:171","msg":"trace[630054248] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"310.64506ms","start":"2026-08-19T09:04:19.280669Z","end":"2026-08-19T09:04:19.591314Z","steps":["trace[630054248] 'process raft request' (duration: 308.941274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:19.591493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:19.28065Z","time spent":"310.763504ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:04:19.656564Z","caller":"traceutil/trace.go:171","msg":"trace[818371634] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"121.96271ms","start":"2026-08-19T09:04:19.534572Z","end":"2026-08-19T09:04:19.656535Z","steps":["trace[818371634] 'read index received' (duration: 55.05643ms)","trace[818371634] 'applied index is now lower than readState.Index' (duration: 66.90518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:19.656919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.144634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:04:19.656909Z","caller":"traceutil/trace.go:171","msg":"trace[1005348827] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"323.315305ms","start":"2026-08-19T09:04:19.33337Z","end":"2026-08-19T09:04:19.656685Z","steps":["trace[1005348827] 'process raft request' (duration: 322.991216ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:19.656963Z","caller":"traceutil/trace.go:171","msg":"trace[1236734506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"122.434282ms","start":"2026-08-19T09:04:19.534517Z","end":"2026-08-19T09:04:19.656952Z","steps":["trace[1236734506] 'agreement among raft nodes before linearized reading' (duration: 122.162004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:19.657082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:19.333351Z","time spent":"323.637044ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:04:19.657125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.856591ms","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-19T09:04:19.657185Z","caller":"traceutil/trace.go:171","msg":"trace[572992239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"110.974424ms","start":"2026-08-19T09:04:19.546196Z","end":"2026-08-19T09:04:19.65717Z","steps":["trace[572992239] 'agreement among raft nodes before linearized reading' (duration: 110.711267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:20.049124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.31852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-19T09:04:20.04918Z","caller":"traceutil/trace.go:171","msg":"trace[1809501067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"183.405002ms","start":"2026-08-19T09:04:19.865762Z","end":"2026-08-19T09:04:20.049167Z","steps":["trace[1809501067] 'range keys from in-memory index tree' (duration: 183.210116ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:20.321811Z","caller":"traceutil/trace.go:171","msg":"trace[1597015122] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"268.607137ms","start":"2026-08-19T09:04:20.053185Z","end":"2026-08-19T09:04:20.321792Z","steps":["trace[1597015122] 'process raft request' (duration: 268.497764ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:20.344754Z","caller":"traceutil/trace.go:171","msg":"trace[324741859] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"133.499745ms","start":"2026-08-19T09:04:20.211231Z","end":"2026-08-19T09:04:20.344731Z","steps":["trace[324741859] 'process raft request' (duration: 133.382292ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:25.483416Z","caller":"traceutil/trace.go:171","msg":"trace[422863293] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"155.385225ms","start":"2026-08-19T09:04:25.32801Z","end":"2026-08-19T09:04:25.483395Z","steps":["trace[422863293] 'process raft request' (duration: 155.279262ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:40.1452Z","caller":"traceutil/trace.go:171","msg":"trace[1307269857] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"131.277948ms","start":"2026-08-19T09:04:40.013871Z","end":"2026-08-19T09:04:40.145149Z","steps":["trace[1307269857] 'process raft request' (duration: 130.736124ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:45.943026Z","caller":"traceutil/trace.go:171","msg":"trace[518532769] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"129.874057ms","start":"2026-08-19T09:04:45.81313Z","end":"2026-08-19T09:04:45.943004Z","steps":["trace[518532769] 'read index received' (duration: 129.794846ms)","trace[518532769] 'applied index is now lower than readState.Index' (duration: 78.021µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:45.943055Z","caller":"traceutil/trace.go:171","msg":"trace[1020870871] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"218.011115ms","start":"2026-08-19T09:04:45.725018Z","end":"2026-08-19T09:04:45.943029Z","steps":["trace[1020870871] 'process raft request' (duration: 217.834981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:45.94373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.3964ms","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-19T09:04:45.943788Z","caller":"traceutil/trace.go:171","msg":"trace[301083976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"130.692768ms","start":"2026-08-19T09:04:45.813077Z","end":"2026-08-19T09:04:45.94377Z","steps":["trace[301083976] 'agreement among raft nodes before linearized reading' (duration: 130.077272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:46.099564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.549768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:04:46.099925Z","caller":"traceutil/trace.go:171","msg":"trace[1768190488] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"156.793513ms","start":"2026-08-19T09:04:45.943104Z","end":"2026-08-19T09:04:46.099897Z","steps":["trace[1768190488] 'read index received' (duration: 34.804014ms)","trace[1768190488] 'applied index is now lower than readState.Index' (duration: 121.987209ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:46.099956Z","caller":"traceutil/trace.go:171","msg":"trace[1746408408] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"171.613989ms","start":"2026-08-19T09:04:45.928306Z","end":"2026-08-19T09:04:46.09992Z","steps":["trace[1746408408] 'process raft request' (duration: 49.607239ms)","trace[1746408408] 'compare' (duration: 121.286702ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:46.100136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.26816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-19T09:04:46.100204Z","caller":"traceutil/trace.go:171","msg":"trace[1778672232] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1343; }","duration":"260.368193ms","start":"2026-08-19T09:04:45.839818Z","end":"2026-08-19T09:04:46.100186Z","steps":["trace[1778672232] 'agreement among raft nodes before linearized reading' (duration: 260.138887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:46.100249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.730912ms","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-19T09:04:46.100294Z","caller":"traceutil/trace.go:171","msg":"trace[994963371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1343; }","duration":"121.824365ms","start":"2026-08-19T09:04:45.978459Z","end":"2026-08-19T09:04:46.100284Z","steps":["trace[994963371] 'agreement among raft nodes before linearized reading' (duration: 121.63975ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:46.099922Z","caller":"traceutil/trace.go:171","msg":"trace[1107983549] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"150.492134ms","start":"2026-08-19T09:04:45.949268Z","end":"2026-08-19T09:04:46.09976Z","steps":["trace[1107983549] 'process raft request' (duration: 150.412622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:46.392465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.770717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:04:46.392537Z","caller":"traceutil/trace.go:171","msg":"trace[705089632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"186.894669ms","start":"2026-08-19T09:04:46.205627Z","end":"2026-08-19T09:04:46.392521Z","steps":["trace[705089632] 'range keys from in-memory index tree' (duration: 186.616811ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:46.764823Z","caller":"traceutil/trace.go:171","msg":"trace[1507377608] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"195.192709ms","start":"2026-08-19T09:04:46.569602Z","end":"2026-08-19T09:04:46.764795Z","steps":["trace[1507377608] 'read index received' (duration: 195.044135ms)","trace[1507377608] 'applied index is now lower than readState.Index' (duration: 147.344µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:46.764941Z","caller":"traceutil/trace.go:171","msg":"trace[1740141771] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"364.763552ms","start":"2026-08-19T09:04:46.400167Z","end":"2026-08-19T09:04:46.76493Z","steps":["trace[1740141771] 'process raft request' (duration: 364.479364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:46.765037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:46.400148Z","time spent":"364.819123ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:04:46.765202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.334071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-19T09:04:46.765254Z","caller":"traceutil/trace.go:171","msg":"trace[835366613] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1345; }","duration":"155.428564ms","start":"2026-08-19T09:04:46.609808Z","end":"2026-08-19T09:04:46.765236Z","steps":["trace[835366613] 'agreement among raft nodes before linearized reading' (duration: 155.26866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:46.765393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.792965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-19T09:04:46.765433Z","caller":"traceutil/trace.go:171","msg":"trace[2121544444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"195.867507ms","start":"2026-08-19T09:04:46.569556Z","end":"2026-08-19T09:04:46.765423Z","steps":["trace[2121544444] 'agreement among raft nodes before linearized reading' (duration: 195.763905ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:47.278463Z","caller":"traceutil/trace.go:171","msg":"trace[1451307653] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"508.70141ms","start":"2026-08-19T09:04:46.769736Z","end":"2026-08-19T09:04:47.278438Z","steps":["trace[1451307653] 'process raft request' (duration: 470.311662ms)","trace[1451307653] 'compare' (duration: 38.131352ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:47.278567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:46.769711Z","time spent":"508.804243ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:04:47.325395Z","caller":"traceutil/trace.go:171","msg":"trace[507152065] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1399; }","duration":"497.220627ms","start":"2026-08-19T09:04:46.828144Z","end":"2026-08-19T09:04:47.325365Z","steps":["trace[507152065] 'read index received' (duration: 411.899994ms)","trace[507152065] 'applied index is now lower than readState.Index' (duration: 85.319073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:47.325523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.3592ms","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-19T09:04:47.325561Z","caller":"traceutil/trace.go:171","msg":"trace[1274846852] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1346; }","duration":"497.418841ms","start":"2026-08-19T09:04:46.828133Z","end":"2026-08-19T09:04:47.325552Z","steps":["trace[1274846852] 'agreement among raft nodes before linearized reading' (duration: 497.330759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:47.325596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:46.828091Z","time spent":"497.496063ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:04:50.953175Z","caller":"traceutil/trace.go:171","msg":"trace[1981705139] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"168.753776ms","start":"2026-08-19T09:04:50.784404Z","end":"2026-08-19T09:04:50.953158Z","steps":["trace[1981705139] 'read index received' (duration: 123.854524ms)","trace[1981705139] 'applied index is now lower than readState.Index' (duration: 44.898432ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:50.953218Z","caller":"traceutil/trace.go:171","msg":"trace[1102074704] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"206.460328ms","start":"2026-08-19T09:04:50.746729Z","end":"2026-08-19T09:04:50.953189Z","steps":["trace[1102074704] 'process raft request' (duration: 161.618167ms)","trace[1102074704] 'compare' (duration: 44.646636ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:50.953314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.9036ms","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-19T09:04:50.953332Z","caller":"traceutil/trace.go:171","msg":"trace[1005184237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"168.961301ms","start":"2026-08-19T09:04:50.784366Z","end":"2026-08-19T09:04:50.953327Z","steps":["trace[1005184237] 'agreement among raft nodes before linearized reading' (duration: 168.851669ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:55.339375Z","caller":"traceutil/trace.go:171","msg":"trace[7331674] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"192.674487ms","start":"2026-08-19T09:04:55.146676Z","end":"2026-08-19T09:04:55.33935Z","steps":["trace[7331674] 'read index received' (duration: 192.633136ms)","trace[7331674] 'applied index is now lower than readState.Index' (duration: 40.321µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:55.339492Z","caller":"traceutil/trace.go:171","msg":"trace[1723117826] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1389; }","duration":"209.567993ms","start":"2026-08-19T09:04:55.129891Z","end":"2026-08-19T09:04:55.339459Z","steps":["trace[1723117826] 'process raft request' (duration: 209.292996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:55.339544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.849171ms","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":"info","ts":"2026-08-19T09:04:55.33958Z","caller":"traceutil/trace.go:171","msg":"trace[1788220349] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1389; }","duration":"192.908322ms","start":"2026-08-19T09:04:55.146662Z","end":"2026-08-19T09:04:55.33957Z","steps":["trace[1788220349] 'agreement among raft nodes before linearized reading' (duration: 192.783169ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:55.342481Z","caller":"traceutil/trace.go:171","msg":"trace[1491063429] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"186.409773ms","start":"2026-08-19T09:04:55.156055Z","end":"2026-08-19T09:04:55.342465Z","steps":["trace[1491063429] 'process raft request' (duration: 186.28463ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:56.144821Z","caller":"traceutil/trace.go:171","msg":"trace[1761098767] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"145.111487ms","start":"2026-08-19T09:04:55.99967Z","end":"2026-08-19T09:04:56.144782Z","steps":["trace[1761098767] 'process raft request' (duration: 144.896082ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:56.794648Z","caller":"traceutil/trace.go:171","msg":"trace[568359556] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"115.323426ms","start":"2026-08-19T09:04:56.679297Z","end":"2026-08-19T09:04:56.794621Z","steps":["trace[568359556] 'process raft request' (duration: 102.885191ms)","trace[568359556] 'compare' (duration: 11.864041ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:00.750503Z","caller":"traceutil/trace.go:171","msg":"trace[991660864] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"491.808028ms","start":"2026-08-19T09:05:00.258672Z","end":"2026-08-19T09:05:00.75048Z","steps":["trace[991660864] 'read index received' (duration: 491.572902ms)","trace[991660864] 'applied index is now lower than readState.Index' (duration: 234.186µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:00.750543Z","caller":"traceutil/trace.go:171","msg":"trace[664632597] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"520.351639ms","start":"2026-08-19T09:05:00.230168Z","end":"2026-08-19T09:05:00.75052Z","steps":["trace[664632597] 'process raft request' (duration: 520.163854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:00.750712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:00.230143Z","time spent":"520.496533ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:05:00.750715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.037933ms","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-19T09:05:00.750775Z","caller":"traceutil/trace.go:171","msg":"trace[1301263412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1418; }","duration":"492.141126ms","start":"2026-08-19T09:05:00.258619Z","end":"2026-08-19T09:05:00.75076Z","steps":["trace[1301263412] 'agreement among raft nodes before linearized reading' (duration: 491.959211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:00.750818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:00.2586Z","time spent":"492.208398ms","remote":"127.0.0.1:52114","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-19T09:05:00.750917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.374925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:05:00.750953Z","caller":"traceutil/trace.go:171","msg":"trace[1088159662] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1418; }","duration":"429.451207ms","start":"2026-08-19T09:05:00.321493Z","end":"2026-08-19T09:05:00.750944Z","steps":["trace[1088159662] 'agreement among raft nodes before linearized reading' (duration: 429.397616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:00.750994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:00.321473Z","time spent":"429.513389ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:05:00.751051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.648949ms","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-19T09:05:00.751104Z","caller":"traceutil/trace.go:171","msg":"trace[1597995126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1418; }","duration":"371.724531ms","start":"2026-08-19T09:05:00.379366Z","end":"2026-08-19T09:05:00.75109Z","steps":["trace[1597995126] 'agreement among raft nodes before linearized reading' (duration: 371.603448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:00.751152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:00.379355Z","time spent":"371.786252ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:05:00.751348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.114921ms","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-19T09:05:00.751659Z","caller":"traceutil/trace.go:171","msg":"trace[1747880398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1418; }","duration":"415.4811ms","start":"2026-08-19T09:05:00.336158Z","end":"2026-08-19T09:05:00.751639Z","steps":["trace[1747880398] 'agreement among raft nodes before linearized reading' (duration: 414.993818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:00.751716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:00.336138Z","time spent":"415.566921ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-19T09:05:08.631867Z","caller":"traceutil/trace.go:171","msg":"trace[1175139000] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"286.745618ms","start":"2026-08-19T09:05:08.345076Z","end":"2026-08-19T09:05:08.631822Z","steps":["trace[1175139000] 'process raft request' (duration: 286.511233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:09.754448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.330484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:05:09.754663Z","caller":"traceutil/trace.go:171","msg":"trace[1547599978] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1513; }","duration":"652.799405ms","start":"2026-08-19T09:05:09.101842Z","end":"2026-08-19T09:05:09.754641Z","steps":["trace[1547599978] 'read index received' (duration: 3.149934ms)","trace[1547599978] 'applied index is now lower than readState.Index' (duration: 649.648291ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:09.754846Z","caller":"traceutil/trace.go:171","msg":"trace[1174918619] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"763.414732ms","start":"2026-08-19T09:05:08.991358Z","end":"2026-08-19T09:05:09.754772Z","steps":["trace[1174918619] 'process raft request' (duration: 763.205207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:09.754897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.052712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:05:09.754945Z","caller":"traceutil/trace.go:171","msg":"trace[943727124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1455; }","duration":"653.139524ms","start":"2026-08-19T09:05:09.101791Z","end":"2026-08-19T09:05:09.754931Z","steps":["trace[943727124] 'agreement among raft nodes before linearized reading' (duration: 652.97048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:09.754996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:09.10177Z","time spent":"653.215035ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:05:09.754954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:08.991336Z","time spent":"763.568566ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:05:09.755209Z","caller":"traceutil/trace.go:171","msg":"trace[2138185371] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"763.869063ms","start":"2026-08-19T09:05:08.99132Z","end":"2026-08-19T09:05:09.755189Z","steps":["trace[2138185371] 'process raft request' (duration: 113.680239ms)","trace[2138185371] 'compare' (duration: 649.188181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:09.755302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:08.991292Z","time spent":"763.963825ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:05:09.755407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.104232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-19T09:05:09.755431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.804997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:05:09.755433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.328856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:05:09.755453Z","caller":"traceutil/trace.go:171","msg":"trace[338768388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1455; }","duration":"291.188074ms","start":"2026-08-19T09:05:09.464252Z","end":"2026-08-19T09:05:09.75544Z","steps":["trace[338768388] 'agreement among raft nodes before linearized reading' (duration: 291.063361ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:05:09.755476Z","caller":"traceutil/trace.go:171","msg":"trace[550147536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"218.885929ms","start":"2026-08-19T09:05:09.536575Z","end":"2026-08-19T09:05:09.75546Z","steps":["trace[550147536] 'agreement among raft nodes before linearized reading' (duration: 218.825598ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:05:09.755481Z","caller":"traceutil/trace.go:171","msg":"trace[1488578178] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1455; }","duration":"190.401828ms","start":"2026-08-19T09:05:09.565067Z","end":"2026-08-19T09:05:09.755469Z","steps":["trace[1488578178] 'agreement among raft nodes before linearized reading' (duration: 190.323286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:10.161808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.848968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:05:10.161908Z","caller":"traceutil/trace.go:171","msg":"trace[25747155] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"401.759429ms","start":"2026-08-19T09:05:09.760131Z","end":"2026-08-19T09:05:10.16189Z","steps":["trace[25747155] 'process raft request' (duration: 255.753617ms)","trace[25747155] 'compare' (duration: 145.748246ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:10.161963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:09.76011Z","time spent":"401.82793ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:05:10.164154Z","caller":"traceutil/trace.go:171","msg":"trace[1919311985] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1515; }","duration":"351.600346ms","start":"2026-08-19T09:05:09.812529Z","end":"2026-08-19T09:05:10.164129Z","steps":["trace[1919311985] 'read index received' (duration: 203.350952ms)","trace[1919311985] 'applied index is now lower than readState.Index' (duration: 148.248314ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:10.164199Z","caller":"traceutil/trace.go:171","msg":"trace[21232912] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"403.804896ms","start":"2026-08-19T09:05:09.760372Z","end":"2026-08-19T09:05:10.164177Z","steps":["trace[21232912] 'process raft request' (duration: 403.657933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:10.164337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:09.760353Z","time spent":"403.905998ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:05:10.164272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.716759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:05:10.164539Z","caller":"traceutil/trace.go:171","msg":"trace[1074894876] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1457; }","duration":"352.011645ms","start":"2026-08-19T09:05:09.8125Z","end":"2026-08-19T09:05:10.164512Z","steps":["trace[1074894876] 'agreement among raft nodes before linearized reading' (duration: 351.711188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:10.164694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:09.812487Z","time spent":"352.17033ms","remote":"127.0.0.1:46526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"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-19T09:05:11.246954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.879009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:05:11.24706Z","caller":"traceutil/trace.go:171","msg":"trace[1208067445] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1463; }","duration":"149.532434ms","start":"2026-08-19T09:05:11.097509Z","end":"2026-08-19T09:05:11.247041Z","steps":["trace[1208067445] 'process raft request' (duration: 26.393819ms)","trace[1208067445] 'compare' (duration: 122.783007ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:39.317381Z","caller":"traceutil/trace.go:171","msg":"trace[1090473553] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1622; }","duration":"165.389314ms","start":"2026-08-19T09:05:39.151968Z","end":"2026-08-19T09:05:39.317357Z","steps":["trace[1090473553] 'read index received' (duration: 165.23329ms)","trace[1090473553] 'applied index is now lower than readState.Index' (duration: 155.004µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:39.317558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.004956ms","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-19T09:05:39.317592Z","caller":"traceutil/trace.go:171","msg":"trace[1020884678] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:1556; }","duration":"108.087017ms","start":"2026-08-19T09:05:39.209494Z","end":"2026-08-19T09:05:39.317581Z","steps":["trace[1020884678] 'agreement among raft nodes before linearized reading' (duration: 108.005056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:39.317597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.613458ms","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-19T09:05:39.317666Z","caller":"traceutil/trace.go:171","msg":"trace[1988270989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1556; }","duration":"165.71285ms","start":"2026-08-19T09:05:39.151938Z","end":"2026-08-19T09:05:39.317651Z","steps":["trace[1988270989] 'agreement among raft nodes before linearized reading' (duration: 165.510596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:39.317714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.758216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-8997q\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-08-19T09:05:39.317788Z","caller":"traceutil/trace.go:171","msg":"trace[57723637] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-8997q; range_end:; response_count:1; response_revision:1556; }","duration":"104.871298ms","start":"2026-08-19T09:05:39.212898Z","end":"2026-08-19T09:05:39.317769Z","steps":["trace[57723637] 'agreement among raft nodes before linearized reading' (duration: 104.671074ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:05:39.317949Z","caller":"traceutil/trace.go:171","msg":"trace[1319088711] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"175.602586ms","start":"2026-08-19T09:05:39.141794Z","end":"2026-08-19T09:05:39.317397Z","steps":["trace[1319088711] 'process raft request' (duration: 175.416672ms)"],"step_count":1}