{"level":"warn","ts":"2026-02-18T14:43:47.360599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T14:43:47.360829Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-02-18T14:43:47.360946Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T14:43:47.361007Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-18T14:43:47.361096Z","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-02-18T14:43:47.361815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-02-18T14:43:47.362271Z","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":2,"max-cpu-available":2,"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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-02-18T14:43:47.367574Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.019979ms"} {"level":"info","ts":"2026-02-18T14:43:47.389839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-02-18T14:43:47.389954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:43:47.390033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-02-18T14:43:47.390078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:43:47.390113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-02-18T14:43:47.390198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-02-18T14:43:47.412072Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:43:47.427117Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:43:47.432275Z","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-02-18T14:43:47.445195Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:43:47.445671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:43:47.445886Z","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-02-18T14:43:47.445985Z","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-02-18T14:43:47.446299Z","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-02-18T14:43:47.446222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-02-18T14:43:47.454986Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-18T14:43:47.460288Z","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-02-18T14:43:47.460567Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-18T14:43:47.4606Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-18T14:43:47.460711Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:43:47.460787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:43:47.890965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:43:47.891151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:43:47.891222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-02-18T14:43:47.891268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:43:47.891328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-18T14:43:47.891381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-02-18T14:43:47.891421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-18T14:43:47.896335Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:43:47.896522Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:43:47.896577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:43:47.8971Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:43:47.897116Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:43:47.897109Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:47.902746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-02-18T14:43:47.902939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:47.903437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:47.904568Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:47.908552Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:43:55.295192Z","caller":"traceutil/trace.go:171","msg":"trace[1336523253] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"287.081288ms","start":"2026-02-18T14:43:55.008095Z","end":"2026-02-18T14:43:55.295176Z","steps":["trace[1336523253] 'process raft request' (duration: 287.002806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:11.816982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:11.426527Z","time spent":"390.450135ms","remote":"127.0.0.1:36524","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T14:44:12.262099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.415629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:12.262198Z","caller":"traceutil/trace.go:171","msg":"trace[1589015481] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"395.841854ms","start":"2026-02-18T14:44:11.866342Z","end":"2026-02-18T14:44:12.262184Z","steps":["trace[1589015481] 'read index received' (duration: 201.902879ms)","trace[1589015481] 'applied index is now lower than readState.Index' (duration: 193.938155ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:12.26228Z","caller":"traceutil/trace.go:171","msg":"trace[1918764927] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"444.329067ms","start":"2026-02-18T14:44:11.817943Z","end":"2026-02-18T14:44:12.262272Z","steps":["trace[1918764927] 'process raft request' (duration: 250.357532ms)","trace[1918764927] 'compare' (duration: 193.284095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:12.262329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:11.817922Z","time spent":"444.385269ms","remote":"127.0.0.1:36524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:12.262566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.220115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:12.262595Z","caller":"traceutil/trace.go:171","msg":"trace[304880509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"396.278567ms","start":"2026-02-18T14:44:11.866309Z","end":"2026-02-18T14:44:12.262587Z","steps":["trace[304880509] 'agreement among raft nodes before linearized reading' (duration: 396.213435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:12.262617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:11.866291Z","time spent":"396.320038ms","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:44:12.262747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.000104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-18T14:44:12.262844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.780383ms","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-02-18T14:44:12.262836Z","caller":"traceutil/trace.go:171","msg":"trace[1627194195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"175.127627ms","start":"2026-02-18T14:44:12.087684Z","end":"2026-02-18T14:44:12.262811Z","steps":["trace[1627194195] 'agreement among raft nodes before linearized reading' (duration: 174.959972ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:12.262867Z","caller":"traceutil/trace.go:171","msg":"trace[747959201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"139.830154ms","start":"2026-02-18T14:44:12.123031Z","end":"2026-02-18T14:44:12.262861Z","steps":["trace[747959201] 'agreement among raft nodes before linearized reading' (duration: 139.786753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:12.263043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.426245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T14:44:12.263063Z","caller":"traceutil/trace.go:171","msg":"trace[307894428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"155.512658ms","start":"2026-02-18T14:44:12.107544Z","end":"2026-02-18T14:44:12.263057Z","steps":["trace[307894428] 'agreement among raft nodes before linearized reading' (duration: 155.443426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:21.154719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.13045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:44:21.154786Z","caller":"traceutil/trace.go:171","msg":"trace[577288801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"840.239693ms","start":"2026-02-18T14:44:20.314531Z","end":"2026-02-18T14:44:21.154771Z","steps":["trace[577288801] 'range keys from in-memory index tree' (duration: 840.030447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:21.154814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:20.314511Z","time spent":"840.295985ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:21.154953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.672399ms","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-02-18T14:44:21.154975Z","caller":"traceutil/trace.go:171","msg":"trace[511009918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"828.713081ms","start":"2026-02-18T14:44:20.326254Z","end":"2026-02-18T14:44:21.154967Z","steps":["trace[511009918] 'range keys from in-memory index tree' (duration: 828.615628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:21.154993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:20.326243Z","time spent":"828.745362ms","remote":"127.0.0.1:36748","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-02-18T14:44:21.155129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.417907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:44:21.155149Z","caller":"traceutil/trace.go:171","msg":"trace[1851969578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"803.468788ms","start":"2026-02-18T14:44:20.351674Z","end":"2026-02-18T14:44:21.155143Z","steps":["trace[1851969578] 'range keys from in-memory index tree' (duration: 803.352195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:21.155177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:20.351663Z","time spent":"803.49756ms","remote":"127.0.0.1:36748","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-02-18T14:44:21.155314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.480829ms","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-02-18T14:44:21.155335Z","caller":"traceutil/trace.go:171","msg":"trace[862545505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"803.695366ms","start":"2026-02-18T14:44:20.351633Z","end":"2026-02-18T14:44:21.155329Z","steps":["trace[862545505] 'range keys from in-memory index tree' (duration: 803.391056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:21.155434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:20.351617Z","time spent":"803.810009ms","remote":"127.0.0.1:36748","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-02-18T14:44:21.155974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.907194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:21.156002Z","caller":"traceutil/trace.go:171","msg":"trace[717122395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"287.963015ms","start":"2026-02-18T14:44:20.868032Z","end":"2026-02-18T14:44:21.155995Z","steps":["trace[717122395] 'range keys from in-memory index tree' (duration: 287.839442ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:24.423557Z","caller":"traceutil/trace.go:171","msg":"trace[89289932] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"311.482004ms","start":"2026-02-18T14:44:24.112052Z","end":"2026-02-18T14:44:24.423534Z","steps":["trace[89289932] 'process raft request' (duration: 311.34307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:24.423712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:24.112029Z","time spent":"311.612759ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:29.196755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309469385736777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:44:29.697293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309469385736777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:44:29.879259Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.212339111s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:44:29.87959Z","caller":"traceutil/trace.go:171","msg":"trace[478907453] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"1.18287601s","start":"2026-02-18T14:44:28.696692Z","end":"2026-02-18T14:44:29.879568Z","steps":["trace[478907453] 'read index received' (duration: 1.182740866s)","trace[478907453] 'applied index is now lower than readState.Index' (duration: 134.264µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:29.879698Z","caller":"traceutil/trace.go:171","msg":"trace[184427934] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.212853456s","start":"2026-02-18T14:44:28.666834Z","end":"2026-02-18T14:44:29.879688Z","steps":["trace[184427934] 'process raft request' (duration: 1.21264228s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.879925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:28.666805Z","time spent":"1.212945689s","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:44:29.880331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183632964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T14:44:29.880413Z","caller":"traceutil/trace.go:171","msg":"trace[829662377] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:498; }","duration":"1.183744146s","start":"2026-02-18T14:44:28.696656Z","end":"2026-02-18T14:44:29.8804Z","steps":["trace[829662377] 'agreement among raft nodes before linearized reading' (duration: 1.183560571s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.880471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:28.696641Z","time spent":"1.183819969s","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:44:29.880722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.956212ms","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-02-18T14:44:29.880787Z","caller":"traceutil/trace.go:171","msg":"trace[1844323879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"662.019684ms","start":"2026-02-18T14:44:29.218756Z","end":"2026-02-18T14:44:29.880775Z","steps":["trace[1844323879] 'agreement among raft nodes before linearized reading' (duration: 661.877089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.880841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.218715Z","time spent":"662.115966ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:29.881076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.859227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4fhw4\" ","response":"range_response_count:1 size:16648"} {"level":"warn","ts":"2026-02-18T14:44:29.88117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.691186ms","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-02-18T14:44:29.881231Z","caller":"traceutil/trace.go:171","msg":"trace[1116043518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"597.770589ms","start":"2026-02-18T14:44:29.283446Z","end":"2026-02-18T14:44:29.881216Z","steps":["trace[1116043518] 'agreement among raft nodes before linearized reading' (duration: 597.633355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.881254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.283437Z","time spent":"597.81221ms","remote":"127.0.0.1:36748","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-02-18T14:44:29.881383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.355795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:44:29.881408Z","caller":"traceutil/trace.go:171","msg":"trace[91596671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"595.395297ms","start":"2026-02-18T14:44:29.286006Z","end":"2026-02-18T14:44:29.881402Z","steps":["trace[91596671] 'agreement among raft nodes before linearized reading' (duration: 595.337625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.881425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.285998Z","time spent":"595.423397ms","remote":"127.0.0.1:36748","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-02-18T14:44:29.881386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.552449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T14:44:29.881185Z","caller":"traceutil/trace.go:171","msg":"trace[1565122408] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4fhw4; range_end:; response_count:1; response_revision:498; }","duration":"504.001272ms","start":"2026-02-18T14:44:29.377171Z","end":"2026-02-18T14:44:29.881172Z","steps":["trace[1565122408] 'agreement among raft nodes before linearized reading' (duration: 503.813836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.881509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.377157Z","time spent":"504.346122ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16671,"request content":"key:\"/registry/pods/kube-system/cilium-4fhw4\" "} {"level":"info","ts":"2026-02-18T14:44:29.881553Z","caller":"traceutil/trace.go:171","msg":"trace[1647055717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"608.710104ms","start":"2026-02-18T14:44:29.272784Z","end":"2026-02-18T14:44:29.881494Z","steps":["trace[1647055717] 'agreement among raft nodes before linearized reading' (duration: 608.425565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:29.881631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.27276Z","time spent":"608.841768ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:30.463469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.522779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:30.463569Z","caller":"traceutil/trace.go:171","msg":"trace[2017831301] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"566.136892ms","start":"2026-02-18T14:44:29.897409Z","end":"2026-02-18T14:44:30.463546Z","steps":["trace[2017831301] 'process raft request' (duration: 162.448848ms)","trace[2017831301] 'compare' (duration: 403.264751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:30.46365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.897395Z","time spent":"566.214284ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:30.468758Z","caller":"traceutil/trace.go:171","msg":"trace[1582927842] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:515; }","duration":"565.045288ms","start":"2026-02-18T14:44:29.903695Z","end":"2026-02-18T14:44:30.46874Z","steps":["trace[1582927842] 'read index received' (duration: 156.247647ms)","trace[1582927842] 'applied index is now lower than readState.Index' (duration: 408.796971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:30.469118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.41022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-18T14:44:30.469154Z","caller":"traceutil/trace.go:171","msg":"trace[380902327] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:503; }","duration":"565.486212ms","start":"2026-02-18T14:44:29.903659Z","end":"2026-02-18T14:44:30.469145Z","steps":["trace[380902327] 'agreement among raft nodes before linearized reading' (duration: 565.137762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:30.469182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.903647Z","time spent":"565.527413ms","remote":"127.0.0.1:36588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-18T14:44:30.469468Z","caller":"traceutil/trace.go:171","msg":"trace[1419186539] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"571.97562ms","start":"2026-02-18T14:44:29.897485Z","end":"2026-02-18T14:44:30.46946Z","steps":["trace[1419186539] 'process raft request' (duration: 571.062602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:30.469525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.897467Z","time spent":"572.023342ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:30.46963Z","caller":"traceutil/trace.go:171","msg":"trace[83106925] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"572.090524ms","start":"2026-02-18T14:44:29.89753Z","end":"2026-02-18T14:44:30.469621Z","steps":["trace[83106925] 'process raft request' (duration: 571.100364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:30.470121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.897527Z","time spent":"572.121825ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:30.470265Z","caller":"traceutil/trace.go:171","msg":"trace[1160188412] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"572.687362ms","start":"2026-02-18T14:44:29.897569Z","end":"2026-02-18T14:44:30.470257Z","steps":["trace[1160188412] 'process raft request' (duration: 571.093504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:30.470775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.897564Z","time spent":"572.716273ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:30.470832Z","caller":"traceutil/trace.go:171","msg":"trace[176644822] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"564.43207ms","start":"2026-02-18T14:44:29.906369Z","end":"2026-02-18T14:44:30.470801Z","steps":["trace[176644822] 'process raft request' (duration: 562.333256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:30.471021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:29.906352Z","time spent":"564.586904ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:32.843132Z","caller":"traceutil/trace.go:171","msg":"trace[688735797] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"360.466021ms","start":"2026-02-18T14:44:32.4826Z","end":"2026-02-18T14:44:32.843066Z","steps":["trace[688735797] 'read index received' (duration: 358.911024ms)","trace[688735797] 'applied index is now lower than readState.Index' (duration: 1.553507ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:32.843981Z","caller":"traceutil/trace.go:171","msg":"trace[12224533] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"379.754232ms","start":"2026-02-18T14:44:32.464209Z","end":"2026-02-18T14:44:32.843963Z","steps":["trace[12224533] 'process raft request' (duration: 377.322128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.844093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.464192Z","time spent":"379.822673ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:32.845431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.813223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.845506Z","caller":"traceutil/trace.go:171","msg":"trace[1223779889] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:522; }","duration":"362.918406ms","start":"2026-02-18T14:44:32.482558Z","end":"2026-02-18T14:44:32.845476Z","steps":["trace[1223779889] 'agreement among raft nodes before linearized reading' (duration: 362.132552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.845631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.482543Z","time spent":"363.075792ms","remote":"127.0.0.1:36536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T14:44:32.846494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.887326ms","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-02-18T14:44:32.846538Z","caller":"traceutil/trace.go:171","msg":"trace[1495295946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"363.958899ms","start":"2026-02-18T14:44:32.482572Z","end":"2026-02-18T14:44:32.846531Z","steps":["trace[1495295946] 'agreement among raft nodes before linearized reading' (duration: 363.859465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.846571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.482559Z","time spent":"364.00584ms","remote":"127.0.0.1:36748","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-02-18T14:44:32.854643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.997254ms","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-02-18T14:44:32.855712Z","caller":"traceutil/trace.go:171","msg":"trace[1211462787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"373.080337ms","start":"2026-02-18T14:44:32.482624Z","end":"2026-02-18T14:44:32.855704Z","steps":["trace[1211462787] 'agreement among raft nodes before linearized reading' (duration: 371.930432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.855818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.48262Z","time spent":"373.18742ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:32.854898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.691397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.856486Z","caller":"traceutil/trace.go:171","msg":"trace[328805534] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"185.316797ms","start":"2026-02-18T14:44:32.671157Z","end":"2026-02-18T14:44:32.856474Z","steps":["trace[328805534] 'agreement among raft nodes before linearized reading' (duration: 183.717898ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:32.854999Z","caller":"traceutil/trace.go:171","msg":"trace[913618751] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"184.4332ms","start":"2026-02-18T14:44:32.670549Z","end":"2026-02-18T14:44:32.854982Z","steps":["trace[913618751] 'process raft request' (duration: 183.920804ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:32.855026Z","caller":"traceutil/trace.go:171","msg":"trace[1372150819] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"184.292535ms","start":"2026-02-18T14:44:32.670728Z","end":"2026-02-18T14:44:32.855021Z","steps":["trace[1372150819] 'process raft request' (duration: 183.79927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.855057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.99524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.857446Z","caller":"traceutil/trace.go:171","msg":"trace[73354493] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"105.376173ms","start":"2026-02-18T14:44:32.752055Z","end":"2026-02-18T14:44:32.857431Z","steps":["trace[73354493] 'agreement among raft nodes before linearized reading' (duration: 102.98269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.855141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.524818ms","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-02-18T14:44:32.858219Z","caller":"traceutil/trace.go:171","msg":"trace[492647825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"370.57543ms","start":"2026-02-18T14:44:32.487598Z","end":"2026-02-18T14:44:32.858174Z","steps":["trace[492647825] 'agreement among raft nodes before linearized reading' (duration: 367.503947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.858331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.487592Z","time spent":"370.729296ms","remote":"127.0.0.1:36748","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-02-18T14:44:32.855168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.471651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.860273Z","caller":"traceutil/trace.go:171","msg":"trace[1477319076] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"189.594977ms","start":"2026-02-18T14:44:32.670669Z","end":"2026-02-18T14:44:32.860264Z","steps":["trace[1477319076] 'agreement among raft nodes before linearized reading' (duration: 184.487831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.855217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.158967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:44:32.860803Z","caller":"traceutil/trace.go:171","msg":"trace[1159365055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"372.748008ms","start":"2026-02-18T14:44:32.488048Z","end":"2026-02-18T14:44:32.860796Z","steps":["trace[1159365055] 'agreement among raft nodes before linearized reading' (duration: 367.131126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.86092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.48803Z","time spent":"372.876391ms","remote":"127.0.0.1:36748","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-02-18T14:44:32.855244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.24042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.861593Z","caller":"traceutil/trace.go:171","msg":"trace[1858595975] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"374.296175ms","start":"2026-02-18T14:44:32.486934Z","end":"2026-02-18T14:44:32.86123Z","steps":["trace[1858595975] 'agreement among raft nodes before linearized reading' (duration: 368.300582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.861682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.486924Z","time spent":"374.749288ms","remote":"127.0.0.1:36536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T14:44:32.855273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.646124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:32.861889Z","caller":"traceutil/trace.go:171","msg":"trace[13388267] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:524; }","duration":"379.267727ms","start":"2026-02-18T14:44:32.482614Z","end":"2026-02-18T14:44:32.861882Z","steps":["trace[13388267] 'agreement among raft nodes before linearized reading' (duration: 372.643574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:32.861957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:32.482609Z","time spent":"379.340579ms","remote":"127.0.0.1:36536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T14:44:41.036775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309469385736984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:44:41.167802Z","caller":"traceutil/trace.go:171","msg":"trace[1125543254] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"884.577989ms","start":"2026-02-18T14:44:40.283206Z","end":"2026-02-18T14:44:41.167784Z","steps":["trace[1125543254] 'process raft request' (duration: 884.471705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.167914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.283189Z","time spent":"884.669742ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18227,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:41.52767Z","caller":"traceutil/trace.go:171","msg":"trace[799244433] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"991.588631ms","start":"2026-02-18T14:44:40.536068Z","end":"2026-02-18T14:44:41.527656Z","steps":["trace[799244433] 'read index received' (duration: 632.073628ms)","trace[799244433] 'applied index is now lower than readState.Index' (duration: 359.514303ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:41.527844Z","caller":"traceutil/trace.go:171","msg":"trace[290920531] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"952.859027ms","start":"2026-02-18T14:44:40.574979Z","end":"2026-02-18T14:44:41.527838Z","steps":["trace[290920531] 'process raft request' (duration: 952.588538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.527924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.574963Z","time spent":"952.900938ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:41.528109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.038705ms","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-02-18T14:44:41.52814Z","caller":"traceutil/trace.go:171","msg":"trace[1285731628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:606; }","duration":"992.070646ms","start":"2026-02-18T14:44:40.536064Z","end":"2026-02-18T14:44:41.528135Z","steps":["trace[1285731628] 'agreement among raft nodes before linearized reading' (duration: 991.953182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.528155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.536025Z","time spent":"992.126487ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:41.528233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.760066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:41.528275Z","caller":"traceutil/trace.go:171","msg":"trace[967624109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"662.819068ms","start":"2026-02-18T14:44:40.865451Z","end":"2026-02-18T14:44:41.52827Z","steps":["trace[967624109] 'agreement among raft nodes before linearized reading' (duration: 662.768936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.528292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.865439Z","time spent":"662.850518ms","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:44:41.528428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.299037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-18T14:44:41.528447Z","caller":"traceutil/trace.go:171","msg":"trace[241084958] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:606; }","duration":"101.357249ms","start":"2026-02-18T14:44:41.427086Z","end":"2026-02-18T14:44:41.528443Z","steps":["trace[241084958] 'agreement among raft nodes before linearized reading' (duration: 101.295727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.529501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.11574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:44:41.529548Z","caller":"traceutil/trace.go:171","msg":"trace[14217953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"599.193943ms","start":"2026-02-18T14:44:40.930346Z","end":"2026-02-18T14:44:41.52954Z","steps":["trace[14217953] 'agreement among raft nodes before linearized reading' (duration: 599.09781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.529566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.930326Z","time spent":"599.236083ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:41.530171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.077936ms","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-02-18T14:44:41.530196Z","caller":"traceutil/trace.go:171","msg":"trace[1142059875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"560.127498ms","start":"2026-02-18T14:44:40.970062Z","end":"2026-02-18T14:44:41.53019Z","steps":["trace[1142059875] 'agreement among raft nodes before linearized reading' (duration: 560.069206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.530215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.970042Z","time spent":"560.168689ms","remote":"127.0.0.1:36748","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-02-18T14:44:41.530312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.139858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4fhw4\" ","response":"range_response_count:1 size:18242"} {"level":"info","ts":"2026-02-18T14:44:41.530334Z","caller":"traceutil/trace.go:171","msg":"trace[389121042] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4fhw4; range_end:; response_count:1; response_revision:606; }","duration":"352.183639ms","start":"2026-02-18T14:44:41.178146Z","end":"2026-02-18T14:44:41.530329Z","steps":["trace[389121042] 'agreement among raft nodes before linearized reading' (duration: 352.130027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.530353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:41.178133Z","time spent":"352.216101ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18265,"request content":"key:\"/registry/pods/kube-system/cilium-4fhw4\" "} {"level":"warn","ts":"2026-02-18T14:44:41.53059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.454478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:44:41.530636Z","caller":"traceutil/trace.go:171","msg":"trace[1224936998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"560.476708ms","start":"2026-02-18T14:44:40.970129Z","end":"2026-02-18T14:44:41.530606Z","steps":["trace[1224936998] 'agreement among raft nodes before linearized reading' (duration: 560.392816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.530656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.9701Z","time spent":"560.551991ms","remote":"127.0.0.1:36748","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-02-18T14:44:41.531665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.41695ms","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-02-18T14:44:41.531688Z","caller":"traceutil/trace.go:171","msg":"trace[322328978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"600.453751ms","start":"2026-02-18T14:44:40.931229Z","end":"2026-02-18T14:44:41.531683Z","steps":["trace[322328978] 'agreement among raft nodes before linearized reading' (duration: 600.39496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:41.531703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:40.931223Z","time spent":"600.476862ms","remote":"127.0.0.1:36748","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-02-18T14:44:50.135351Z","caller":"traceutil/trace.go:171","msg":"trace[882229858] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"463.641918ms","start":"2026-02-18T14:44:49.671688Z","end":"2026-02-18T14:44:50.13533Z","steps":["trace[882229858] 'read index received' (duration: 463.443542ms)","trace[882229858] 'applied index is now lower than readState.Index' (duration: 197.726µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:50.135549Z","caller":"traceutil/trace.go:171","msg":"trace[1418050555] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"482.789423ms","start":"2026-02-18T14:44:49.652752Z","end":"2026-02-18T14:44:50.135541Z","steps":["trace[1418050555] 'process raft request' (duration: 482.440523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.135636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.652736Z","time spent":"482.831044ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:50.1358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.114912ms","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-02-18T14:44:50.135823Z","caller":"traceutil/trace.go:171","msg":"trace[857004942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"464.160784ms","start":"2026-02-18T14:44:49.671654Z","end":"2026-02-18T14:44:50.135815Z","steps":["trace[857004942] 'agreement among raft nodes before linearized reading' (duration: 464.088842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.135843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.671639Z","time spent":"464.197894ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:50.135938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.954733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:44:50.135956Z","caller":"traceutil/trace.go:171","msg":"trace[702716440] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:689; }","duration":"456.984944ms","start":"2026-02-18T14:44:49.678966Z","end":"2026-02-18T14:44:50.135951Z","steps":["trace[702716440] 'agreement among raft nodes before linearized reading' (duration: 456.949453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.135973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.67896Z","time spent":"457.008275ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:44:50.136121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.313484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:50.136141Z","caller":"traceutil/trace.go:171","msg":"trace[5886780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"267.369386ms","start":"2026-02-18T14:44:49.868766Z","end":"2026-02-18T14:44:50.136135Z","steps":["trace[5886780] 'agreement among raft nodes before linearized reading' (duration: 267.339805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.136284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.443072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:44:50.136302Z","caller":"traceutil/trace.go:171","msg":"trace[1651831079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"429.480183ms","start":"2026-02-18T14:44:49.706817Z","end":"2026-02-18T14:44:50.136297Z","steps":["trace[1651831079] 'agreement among raft nodes before linearized reading' (duration: 429.400511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.136318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.70681Z","time spent":"429.503614ms","remote":"127.0.0.1:36748","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-02-18T14:44:50.136417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.434512ms","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-02-18T14:44:50.136433Z","caller":"traceutil/trace.go:171","msg":"trace[1469152047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"430.463253ms","start":"2026-02-18T14:44:49.705965Z","end":"2026-02-18T14:44:50.136428Z","steps":["trace[1469152047] 'agreement among raft nodes before linearized reading' (duration: 430.415832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.136449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.705958Z","time spent":"430.487244ms","remote":"127.0.0.1:36748","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-02-18T14:44:50.136605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.067366ms","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-02-18T14:44:50.13667Z","caller":"traceutil/trace.go:171","msg":"trace[895222387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"439.158129ms","start":"2026-02-18T14:44:49.697494Z","end":"2026-02-18T14:44:50.136652Z","steps":["trace[895222387] 'agreement among raft nodes before linearized reading' (duration: 438.961733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:50.136702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:49.697486Z","time spent":"439.210461ms","remote":"127.0.0.1:36748","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-02-18T14:44:53.764796Z","caller":"traceutil/trace.go:171","msg":"trace[1198823245] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"295.692531ms","start":"2026-02-18T14:44:53.469087Z","end":"2026-02-18T14:44:53.764779Z","steps":["trace[1198823245] 'read index received' (duration: 295.593998ms)","trace[1198823245] 'applied index is now lower than readState.Index' (duration: 97.793µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:53.764811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:53.463981Z","time spent":"300.824098ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T14:44:53.764928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.823425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vq9qr\" ","response":"range_response_count:1 size:5024"} {"level":"info","ts":"2026-02-18T14:44:53.764947Z","caller":"traceutil/trace.go:171","msg":"trace[670019320] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-vq9qr; range_end:; response_count:1; response_revision:700; }","duration":"295.872477ms","start":"2026-02-18T14:44:53.469069Z","end":"2026-02-18T14:44:53.764941Z","steps":["trace[670019320] 'agreement among raft nodes before linearized reading' (duration: 295.770664ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:54.224164Z","caller":"traceutil/trace.go:171","msg":"trace[724679822] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"457.978003ms","start":"2026-02-18T14:44:53.766166Z","end":"2026-02-18T14:44:54.224144Z","steps":["trace[724679822] 'process raft request' (duration: 441.382085ms)","trace[724679822] 'compare' (duration: 16.208186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:54.224279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:53.766154Z","time spent":"458.053166ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:44:54.405536Z","caller":"traceutil/trace.go:171","msg":"trace[1250159085] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:725; }","duration":"258.619348ms","start":"2026-02-18T14:44:54.146903Z","end":"2026-02-18T14:44:54.405523Z","steps":["trace[1250159085] 'read index received' (duration: 60.653445ms)","trace[1250159085] 'applied index is now lower than readState.Index' (duration: 197.965403ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:54.405687Z","caller":"traceutil/trace.go:171","msg":"trace[723596207] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"634.79352ms","start":"2026-02-18T14:44:53.770888Z","end":"2026-02-18T14:44:54.405681Z","steps":["trace[723596207] 'process raft request' (duration: 634.555722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:54.40575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:53.770871Z","time spent":"634.83131ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:54.405887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.983919ms","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-02-18T14:44:54.405902Z","caller":"traceutil/trace.go:171","msg":"trace[933910733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"258.998949ms","start":"2026-02-18T14:44:54.146898Z","end":"2026-02-18T14:44:54.405897Z","steps":["trace[933910733] 'agreement among raft nodes before linearized reading' (duration: 258.943688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:54.406012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.022198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T14:44:54.406024Z","caller":"traceutil/trace.go:171","msg":"trace[824967251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"255.050319ms","start":"2026-02-18T14:44:54.15097Z","end":"2026-02-18T14:44:54.40602Z","steps":["trace[824967251] 'agreement among raft nodes before linearized reading' (duration: 255.011628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:54.406225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.308256ms","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-02-18T14:44:54.40624Z","caller":"traceutil/trace.go:171","msg":"trace[256727537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"237.336747ms","start":"2026-02-18T14:44:54.168899Z","end":"2026-02-18T14:44:54.406235Z","steps":["trace[256727537] 'agreement among raft nodes before linearized reading' (duration: 237.295896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:54.406313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.404243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T14:44:54.406358Z","caller":"traceutil/trace.go:171","msg":"trace[1140510280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"244.431414ms","start":"2026-02-18T14:44:54.161891Z","end":"2026-02-18T14:44:54.406323Z","steps":["trace[1140510280] 'agreement among raft nodes before linearized reading' (duration: 244.398303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:54.406436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.903592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:44:54.406447Z","caller":"traceutil/trace.go:171","msg":"trace[372736200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"249.930372ms","start":"2026-02-18T14:44:54.156513Z","end":"2026-02-18T14:44:54.406444Z","steps":["trace[372736200] 'agreement among raft nodes before linearized reading' (duration: 249.895551ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:11.598028Z","caller":"traceutil/trace.go:171","msg":"trace[1274341615] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"156.839676ms","start":"2026-02-18T14:45:11.441165Z","end":"2026-02-18T14:45:11.598004Z","steps":["trace[1274341615] 'process raft request' (duration: 156.432513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:12.840146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.538248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:12.840367Z","caller":"traceutil/trace.go:171","msg":"trace[1217658337] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"282.208629ms","start":"2026-02-18T14:45:12.558142Z","end":"2026-02-18T14:45:12.840351Z","steps":["trace[1217658337] 'read index received' (duration: 20.319361ms)","trace[1217658337] 'applied index is now lower than readState.Index' (duration: 261.888058ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:12.840435Z","caller":"traceutil/trace.go:171","msg":"trace[332718478] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"287.12891ms","start":"2026-02-18T14:45:12.553286Z","end":"2026-02-18T14:45:12.840415Z","steps":["trace[332718478] 'process raft request' (duration: 111.246622ms)","trace[332718478] 'compare' (duration: 175.444185ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:12.840569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.422756ms","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-02-18T14:45:12.840608Z","caller":"traceutil/trace.go:171","msg":"trace[772115528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"282.463057ms","start":"2026-02-18T14:45:12.558137Z","end":"2026-02-18T14:45:12.8406Z","steps":["trace[772115528] 'agreement among raft nodes before linearized reading' (duration: 282.328073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:12.840693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.724718ms","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-02-18T14:45:12.84073Z","caller":"traceutil/trace.go:171","msg":"trace[1682046322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"253.78252ms","start":"2026-02-18T14:45:12.586933Z","end":"2026-02-18T14:45:12.840715Z","steps":["trace[1682046322] 'agreement among raft nodes before linearized reading' (duration: 253.685637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:12.840803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.715038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:45:12.840821Z","caller":"traceutil/trace.go:171","msg":"trace[1824435815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"218.746099ms","start":"2026-02-18T14:45:12.622071Z","end":"2026-02-18T14:45:12.840817Z","steps":["trace[1824435815] 'agreement among raft nodes before linearized reading' (duration: 218.700177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:12.840875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.597089ms","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-02-18T14:45:12.840886Z","caller":"traceutil/trace.go:171","msg":"trace[1687612145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"226.62802ms","start":"2026-02-18T14:45:12.614255Z","end":"2026-02-18T14:45:12.840883Z","steps":["trace[1687612145] 'agreement among raft nodes before linearized reading' (duration: 226.595789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:13.1847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.222995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:13.184777Z","caller":"traceutil/trace.go:171","msg":"trace[1839773598] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"337.993535ms","start":"2026-02-18T14:45:12.846767Z","end":"2026-02-18T14:45:13.184761Z","steps":["trace[1839773598] 'process raft request' (duration: 115.658396ms)","trace[1839773598] 'compare' (duration: 222.05154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:13.184819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:12.84675Z","time spent":"338.051047ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:13.463766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.563595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:13.463887Z","caller":"traceutil/trace.go:171","msg":"trace[1230117896] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"616.823812ms","start":"2026-02-18T14:45:12.847051Z","end":"2026-02-18T14:45:13.463875Z","steps":["trace[1230117896] 'process raft request' (duration: 616.800191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:13.463918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:12.847046Z","time spent":"616.858443ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:13.46408Z","caller":"traceutil/trace.go:171","msg":"trace[1385115296] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"617.188243ms","start":"2026-02-18T14:45:12.846886Z","end":"2026-02-18T14:45:13.464074Z","steps":["trace[1385115296] 'process raft request' (duration: 447.270307ms)","trace[1385115296] 'compare' (duration: 169.224775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:13.464106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:12.846882Z","time spent":"617.210263ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:13.464168Z","caller":"traceutil/trace.go:171","msg":"trace[772875024] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"617.140391ms","start":"2026-02-18T14:45:12.847022Z","end":"2026-02-18T14:45:13.464162Z","steps":["trace[772875024] 'process raft request' (duration: 616.802861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:13.464192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:12.847016Z","time spent":"617.162972ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:19.631735Z","caller":"traceutil/trace.go:171","msg":"trace[1452496019] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"135.703579ms","start":"2026-02-18T14:45:19.496009Z","end":"2026-02-18T14:45:19.631713Z","steps":["trace[1452496019] 'read index received' (duration: 135.536664ms)","trace[1452496019] 'applied index is now lower than readState.Index' (duration: 165.935µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:19.632016Z","caller":"traceutil/trace.go:171","msg":"trace[539004486] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"136.859695ms","start":"2026-02-18T14:45:19.49514Z","end":"2026-02-18T14:45:19.632Z","steps":["trace[539004486] 'process raft request' (duration: 136.458303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:19.632371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.341069ms","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-02-18T14:45:19.632434Z","caller":"traceutil/trace.go:171","msg":"trace[813581540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"136.416771ms","start":"2026-02-18T14:45:19.496005Z","end":"2026-02-18T14:45:19.632422Z","steps":["trace[813581540] 'agreement among raft nodes before linearized reading' (duration: 136.198464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:19.632645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.993048ms","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-02-18T14:45:19.63274Z","caller":"traceutil/trace.go:171","msg":"trace[1759975583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"135.142453ms","start":"2026-02-18T14:45:19.497588Z","end":"2026-02-18T14:45:19.63273Z","steps":["trace[1759975583] 'agreement among raft nodes before linearized reading' (duration: 134.991778ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:20.715119Z","caller":"traceutil/trace.go:171","msg":"trace[1898001179] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"103.076942ms","start":"2026-02-18T14:45:20.612024Z","end":"2026-02-18T14:45:20.715101Z","steps":["trace[1898001179] 'process raft request' (duration: 102.952868ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:21.887419Z","caller":"traceutil/trace.go:171","msg":"trace[1964707787] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"152.760659ms","start":"2026-02-18T14:45:21.73464Z","end":"2026-02-18T14:45:21.8874Z","steps":["trace[1964707787] 'read index received' (duration: 114.582905ms)","trace[1964707787] 'applied index is now lower than readState.Index' (duration: 38.177084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:21.887565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.916894ms","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-02-18T14:45:21.887586Z","caller":"traceutil/trace.go:171","msg":"trace[1870700024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"152.968746ms","start":"2026-02-18T14:45:21.734611Z","end":"2026-02-18T14:45:21.88758Z","steps":["trace[1870700024] 'agreement among raft nodes before linearized reading' (duration: 152.848612ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:21.887732Z","caller":"traceutil/trace.go:171","msg":"trace[281295580] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"153.925594ms","start":"2026-02-18T14:45:21.733774Z","end":"2026-02-18T14:45:21.887699Z","steps":["trace[281295580] 'process raft request' (duration: 115.492202ms)","trace[281295580] 'compare' (duration: 38.072581ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:24.832545Z","caller":"traceutil/trace.go:171","msg":"trace[1753120450] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"104.560524ms","start":"2026-02-18T14:45:24.727961Z","end":"2026-02-18T14:45:24.832522Z","steps":["trace[1753120450] 'process raft request' (duration: 104.369818ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:25.440413Z","caller":"traceutil/trace.go:171","msg":"trace[976919281] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"179.553642ms","start":"2026-02-18T14:45:25.260844Z","end":"2026-02-18T14:45:25.440397Z","steps":["trace[976919281] 'read index received' (duration: 179.365646ms)","trace[976919281] 'applied index is now lower than readState.Index' (duration: 187.466µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:25.440497Z","caller":"traceutil/trace.go:171","msg":"trace[1726373786] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"315.114936ms","start":"2026-02-18T14:45:25.125375Z","end":"2026-02-18T14:45:25.44049Z","steps":["trace[1726373786] 'process raft request' (duration: 314.896809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:25.440579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:25.125361Z","time spent":"315.151207ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:25.44093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.074978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:45:25.440977Z","caller":"traceutil/trace.go:171","msg":"trace[1337955099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"180.170551ms","start":"2026-02-18T14:45:25.260793Z","end":"2026-02-18T14:45:25.440964Z","steps":["trace[1337955099] 'agreement among raft nodes before linearized reading' (duration: 180.019736ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:26.190115Z","caller":"traceutil/trace.go:171","msg":"trace[545960117] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"242.126578ms","start":"2026-02-18T14:45:25.94797Z","end":"2026-02-18T14:45:26.190096Z","steps":["trace[545960117] 'process raft request' (duration: 201.238453ms)","trace[545960117] 'compare' (duration: 40.813373ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:28.225737Z","caller":"traceutil/trace.go:171","msg":"trace[2010936384] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:889; }","duration":"445.295558ms","start":"2026-02-18T14:45:27.780426Z","end":"2026-02-18T14:45:28.225721Z","steps":["trace[2010936384] 'read index received' (duration: 445.139583ms)","trace[2010936384] 'applied index is now lower than readState.Index' (duration: 155.345µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:28.225838Z","caller":"traceutil/trace.go:171","msg":"trace[161186418] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"769.897369ms","start":"2026-02-18T14:45:27.455911Z","end":"2026-02-18T14:45:28.225808Z","steps":["trace[161186418] 'process raft request' (duration: 769.701203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.225913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.482504ms","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-02-18T14:45:28.225933Z","caller":"traceutil/trace.go:171","msg":"trace[2044777246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"445.554746ms","start":"2026-02-18T14:45:27.780373Z","end":"2026-02-18T14:45:28.225928Z","steps":["trace[2044777246] 'agreement among raft nodes before linearized reading' (duration: 445.434053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.225952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:27.780354Z","time spent":"445.593787ms","remote":"127.0.0.1:36748","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-02-18T14:45:28.225968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:27.455884Z","time spent":"770.011191ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:28.226147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.461515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:28.226172Z","caller":"traceutil/trace.go:171","msg":"trace[1867275551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"361.501626ms","start":"2026-02-18T14:45:27.864658Z","end":"2026-02-18T14:45:28.22616Z","steps":["trace[1867275551] 'agreement among raft nodes before linearized reading' (duration: 361.475555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.226184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:27.864644Z","time spent":"361.537268ms","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:45:28.226348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.411205ms","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-02-18T14:45:28.226362Z","caller":"traceutil/trace.go:171","msg":"trace[1213840046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"319.459036ms","start":"2026-02-18T14:45:27.906898Z","end":"2026-02-18T14:45:28.226358Z","steps":["trace[1213840046] 'agreement among raft nodes before linearized reading' (duration: 319.414255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.226374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:27.90688Z","time spent":"319.491947ms","remote":"127.0.0.1:36748","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-02-18T14:45:28.580863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.394906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:28.580979Z","caller":"traceutil/trace.go:171","msg":"trace[505778308] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:890; }","duration":"170.092139ms","start":"2026-02-18T14:45:28.410875Z","end":"2026-02-18T14:45:28.580967Z","steps":["trace[505778308] 'read index received' (duration: 99.167283ms)","trace[505778308] 'applied index is now lower than readState.Index' (duration: 70.924356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:28.581027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.15924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:28.581041Z","caller":"traceutil/trace.go:171","msg":"trace[1095869646] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:859; }","duration":"170.210332ms","start":"2026-02-18T14:45:28.410826Z","end":"2026-02-18T14:45:28.581036Z","steps":["trace[1095869646] 'agreement among raft nodes before linearized reading' (duration: 170.167901ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:28.581198Z","caller":"traceutil/trace.go:171","msg":"trace[1427759091] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"348.959931ms","start":"2026-02-18T14:45:28.232231Z","end":"2026-02-18T14:45:28.581191Z","steps":["trace[1427759091] 'process raft request' (duration: 93.184234ms)","trace[1427759091] 'compare' (duration: 255.132997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:28.581227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:28.232035Z","time spent":"349.175317ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:28.581288Z","caller":"traceutil/trace.go:171","msg":"trace[1088546571] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"338.052283ms","start":"2026-02-18T14:45:28.243229Z","end":"2026-02-18T14:45:28.581281Z","steps":["trace[1088546571] 'process raft request' (duration: 337.692012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.58131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:28.243209Z","time spent":"338.089225ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:28.58138Z","caller":"traceutil/trace.go:171","msg":"trace[1790152294] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"336.516797ms","start":"2026-02-18T14:45:28.244858Z","end":"2026-02-18T14:45:28.581375Z","steps":["trace[1790152294] 'process raft request' (duration: 336.090424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.581407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:28.24485Z","time spent":"336.542399ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:28.970604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.794371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:28.97066Z","caller":"traceutil/trace.go:171","msg":"trace[42921338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"103.887174ms","start":"2026-02-18T14:45:28.86676Z","end":"2026-02-18T14:45:28.970648Z","steps":["trace[42921338] 'range keys from in-memory index tree' (duration: 103.747249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:28.971032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.935965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:28.971049Z","caller":"traceutil/trace.go:171","msg":"trace[464756874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"127.977326ms","start":"2026-02-18T14:45:28.843065Z","end":"2026-02-18T14:45:28.971043Z","steps":["trace[464756874] 'range keys from in-memory index tree' (duration: 127.838092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:33.237049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.752633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:33.237128Z","caller":"traceutil/trace.go:171","msg":"trace[1702511034] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"580.892522ms","start":"2026-02-18T14:45:32.65622Z","end":"2026-02-18T14:45:33.237113Z","steps":["trace[1702511034] 'process raft request' (duration: 196.003865ms)","trace[1702511034] 'compare' (duration: 384.31343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:33.237168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:32.656209Z","time spent":"580.939274ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:33.301227Z","caller":"traceutil/trace.go:171","msg":"trace[662868409] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"643.413757ms","start":"2026-02-18T14:45:32.657794Z","end":"2026-02-18T14:45:33.301208Z","steps":["trace[662868409] 'process raft request' (duration: 643.334584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:33.301327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:32.657786Z","time spent":"643.484718ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:33.327432Z","caller":"traceutil/trace.go:171","msg":"trace[2056707729] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"326.630531ms","start":"2026-02-18T14:45:33.000787Z","end":"2026-02-18T14:45:33.327418Z","steps":["trace[2056707729] 'read index received' (duration: 300.409595ms)","trace[2056707729] 'applied index is now lower than readState.Index' (duration: 26.220386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:33.327568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.764865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:33.327592Z","caller":"traceutil/trace.go:171","msg":"trace[1321304572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"326.825146ms","start":"2026-02-18T14:45:33.000759Z","end":"2026-02-18T14:45:33.327584Z","steps":["trace[1321304572] 'agreement among raft nodes before linearized reading' (duration: 326.710143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:33.327611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.000734Z","time spent":"326.872498ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T14:45:33.327763Z","caller":"traceutil/trace.go:171","msg":"trace[1379146498] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"416.783914ms","start":"2026-02-18T14:45:32.910974Z","end":"2026-02-18T14:45:33.327758Z","steps":["trace[1379146498] 'process raft request' (duration: 416.372151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:33.32781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:32.910954Z","time spent":"416.826124ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:33.494335Z","caller":"traceutil/trace.go:171","msg":"trace[1082671510] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"163.701336ms","start":"2026-02-18T14:45:33.330615Z","end":"2026-02-18T14:45:33.494317Z","steps":["trace[1082671510] 'process raft request' (duration: 152.631655ms)","trace[1082671510] 'compare' (duration: 10.994289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:41.699283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.575904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:41.699341Z","caller":"traceutil/trace.go:171","msg":"trace[1298195837] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:892; }","duration":"126.663276ms","start":"2026-02-18T14:45:41.57266Z","end":"2026-02-18T14:45:41.699323Z","steps":["trace[1298195837] 'count revisions from in-memory index tree' (duration: 126.520532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:41.699451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.607542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-02-18T14:45:41.699489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.82696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:41.699492Z","caller":"traceutil/trace.go:171","msg":"trace[1001557949] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:892; }","duration":"268.657073ms","start":"2026-02-18T14:45:41.430816Z","end":"2026-02-18T14:45:41.699473Z","steps":["trace[1001557949] 'range keys from in-memory index tree' (duration: 268.494088ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:41.699504Z","caller":"traceutil/trace.go:171","msg":"trace[296886950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"185.863481ms","start":"2026-02-18T14:45:41.513634Z","end":"2026-02-18T14:45:41.699498Z","steps":["trace[296886950] 'range keys from in-memory index tree' (duration: 185.766628ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:43.01869Z","caller":"traceutil/trace.go:171","msg":"trace[66705594] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"151.247244ms","start":"2026-02-18T14:45:42.867426Z","end":"2026-02-18T14:45:43.018674Z","steps":["trace[66705594] 'read index received' (duration: 151.12465ms)","trace[66705594] 'applied index is now lower than readState.Index' (duration: 122.024µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:43.018788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.344827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:43.018806Z","caller":"traceutil/trace.go:171","msg":"trace[1869620057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"151.407919ms","start":"2026-02-18T14:45:42.867393Z","end":"2026-02-18T14:45:43.018801Z","steps":["trace[1869620057] 'agreement among raft nodes before linearized reading' (duration: 151.341147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:43.019036Z","caller":"traceutil/trace.go:171","msg":"trace[1542131586] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"329.362854ms","start":"2026-02-18T14:45:42.689665Z","end":"2026-02-18T14:45:43.019028Z","steps":["trace[1542131586] 'process raft request' (duration: 328.928881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:43.019088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:42.689656Z","time spent":"329.398754ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:43.924153Z","caller":"traceutil/trace.go:171","msg":"trace[320220234] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"203.942393ms","start":"2026-02-18T14:45:43.720196Z","end":"2026-02-18T14:45:43.924138Z","steps":["trace[320220234] 'process raft request' (duration: 203.666415ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:45.957862Z","caller":"traceutil/trace.go:171","msg":"trace[121877689] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"237.027465ms","start":"2026-02-18T14:45:45.720815Z","end":"2026-02-18T14:45:45.957842Z","steps":["trace[121877689] 'process raft request' (duration: 236.483468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:47.599114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.726567ms","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-02-18T14:45:47.599161Z","caller":"traceutil/trace.go:171","msg":"trace[1503002463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"205.827969ms","start":"2026-02-18T14:45:47.393322Z","end":"2026-02-18T14:45:47.59915Z","steps":["trace[1503002463] 'range keys from in-memory index tree' (duration: 205.606253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:47.599288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.770748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4738"} {"level":"info","ts":"2026-02-18T14:45:47.599301Z","caller":"traceutil/trace.go:171","msg":"trace[1223147287] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:923; }","duration":"193.81078ms","start":"2026-02-18T14:45:47.405486Z","end":"2026-02-18T14:45:47.599297Z","steps":["trace[1223147287] 'range keys from in-memory index tree' (duration: 193.691806ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:47.788357Z","caller":"traceutil/trace.go:171","msg":"trace[326243284] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"182.484279ms","start":"2026-02-18T14:45:47.605853Z","end":"2026-02-18T14:45:47.788337Z","steps":["trace[326243284] 'process raft request' (duration: 182.319265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:48.266274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.712254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:48.266341Z","caller":"traceutil/trace.go:171","msg":"trace[1265474237] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"538.972746ms","start":"2026-02-18T14:45:47.727356Z","end":"2026-02-18T14:45:48.266329Z","steps":["trace[1265474237] 'read index received' (duration: 61.581785ms)","trace[1265474237] 'applied index is now lower than readState.Index' (duration: 477.390151ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:48.266476Z","caller":"traceutil/trace.go:171","msg":"trace[2062393089] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"658.88288ms","start":"2026-02-18T14:45:47.607586Z","end":"2026-02-18T14:45:48.266469Z","steps":["trace[2062393089] 'process raft request' (duration: 378.920478ms)","trace[2062393089] 'compare' (duration: 279.436836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:48.266522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.607573Z","time spent":"658.928801ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:48.266728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.369939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7glzm\" ","response":"range_response_count:1 size:10920"} {"level":"info","ts":"2026-02-18T14:45:48.266752Z","caller":"traceutil/trace.go:171","msg":"trace[1262850103] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7glzm; range_end:; response_count:1; response_revision:925; }","duration":"539.415359ms","start":"2026-02-18T14:45:47.72733Z","end":"2026-02-18T14:45:48.266746Z","steps":["trace[1262850103] 'agreement among raft nodes before linearized reading' (duration: 539.330827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:48.266774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.727318Z","time spent":"539.452011ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10943,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7glzm\" "} {"level":"warn","ts":"2026-02-18T14:45:48.266888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.271766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:48.266911Z","caller":"traceutil/trace.go:171","msg":"trace[227308926] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:925; }","duration":"493.298107ms","start":"2026-02-18T14:45:47.773608Z","end":"2026-02-18T14:45:48.266906Z","steps":["trace[227308926] 'agreement among raft nodes before linearized reading' (duration: 493.261286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:48.266932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.773574Z","time spent":"493.353309ms","remote":"127.0.0.1:36878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:45:48.267118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.033785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:48.267143Z","caller":"traceutil/trace.go:171","msg":"trace[1409427902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"235.096267ms","start":"2026-02-18T14:45:48.032042Z","end":"2026-02-18T14:45:48.267138Z","steps":["trace[1409427902] 'agreement among raft nodes before linearized reading' (duration: 235.032545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:48.268436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.684761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:48.268467Z","caller":"traceutil/trace.go:171","msg":"trace[312901211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"400.742103ms","start":"2026-02-18T14:45:47.867717Z","end":"2026-02-18T14:45:48.268459Z","steps":["trace[312901211] 'agreement among raft nodes before linearized reading' (duration: 400.696381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:48.268595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.867698Z","time spent":"400.783224ms","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:45:48.283145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.98682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T14:45:48.283312Z","caller":"traceutil/trace.go:171","msg":"trace[1634859904] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:925; }","duration":"376.100024ms","start":"2026-02-18T14:45:47.907108Z","end":"2026-02-18T14:45:48.283208Z","steps":["trace[1634859904] 'agreement among raft nodes before linearized reading' (duration: 360.098144ms)","trace[1634859904] 'range keys from bolt db' (duration: 15.908667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:48.283343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.90709Z","time spent":"376.245909ms","remote":"127.0.0.1:36878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-18T14:45:48.469301Z","caller":"traceutil/trace.go:171","msg":"trace[352025243] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"182.811789ms","start":"2026-02-18T14:45:48.286472Z","end":"2026-02-18T14:45:48.469284Z","steps":["trace[352025243] 'process raft request' (duration: 182.773688ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:48.469635Z","caller":"traceutil/trace.go:171","msg":"trace[392011506] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"192.606024ms","start":"2026-02-18T14:45:48.277022Z","end":"2026-02-18T14:45:48.469628Z","steps":["trace[392011506] 'process raft request' (duration: 187.733738ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:48.471905Z","caller":"traceutil/trace.go:171","msg":"trace[1769551642] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"123.692768ms","start":"2026-02-18T14:45:48.348203Z","end":"2026-02-18T14:45:48.471896Z","steps":["trace[1769551642] 'process raft request' (duration: 123.122601ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:48.472076Z","caller":"traceutil/trace.go:171","msg":"trace[436093908] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"123.142901ms","start":"2026-02-18T14:45:48.348925Z","end":"2026-02-18T14:45:48.472068Z","steps":["trace[436093908] 'process raft request' (duration: 122.467551ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:48.473087Z","caller":"traceutil/trace.go:171","msg":"trace[264186694] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"121.147201ms","start":"2026-02-18T14:45:48.351932Z","end":"2026-02-18T14:45:48.473079Z","steps":["trace[264186694] 'process raft request' (duration: 119.491781ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:53.94449Z","caller":"traceutil/trace.go:171","msg":"trace[1090284260] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"117.575064ms","start":"2026-02-18T14:45:53.826888Z","end":"2026-02-18T14:45:53.944463Z","steps":["trace[1090284260] 'read index received' (duration: 117.300516ms)","trace[1090284260] 'applied index is now lower than readState.Index' (duration: 273.538µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:53.944622Z","caller":"traceutil/trace.go:171","msg":"trace[1853430684] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"312.796546ms","start":"2026-02-18T14:45:53.631814Z","end":"2026-02-18T14:45:53.94461Z","steps":["trace[1853430684] 'process raft request' (duration: 312.396734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:53.944728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:53.631791Z","time spent":"312.858438ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:53.945018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.10165ms","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-02-18T14:45:53.945099Z","caller":"traceutil/trace.go:171","msg":"trace[1448460021] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:960; }","duration":"118.215424ms","start":"2026-02-18T14:45:53.826857Z","end":"2026-02-18T14:45:53.945073Z","steps":["trace[1448460021] 'agreement among raft nodes before linearized reading' (duration: 118.10574ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:59.842843Z","caller":"traceutil/trace.go:171","msg":"trace[355957529] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"350.490777ms","start":"2026-02-18T14:45:59.492328Z","end":"2026-02-18T14:45:59.842819Z","steps":["trace[355957529] 'read index received' (duration: 350.28343ms)","trace[355957529] 'applied index is now lower than readState.Index' (duration: 206.327µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:59.843041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.777544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:59.843075Z","caller":"traceutil/trace.go:171","msg":"trace[161236269] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:987; }","duration":"350.867928ms","start":"2026-02-18T14:45:59.492197Z","end":"2026-02-18T14:45:59.843065Z","steps":["trace[161236269] 'agreement among raft nodes before linearized reading' (duration: 350.713943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:59.84311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:59.492178Z","time spent":"350.922339ms","remote":"127.0.0.1:36916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:45:59.843458Z","caller":"traceutil/trace.go:171","msg":"trace[1243968898] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"460.548805ms","start":"2026-02-18T14:45:59.382896Z","end":"2026-02-18T14:45:59.843445Z","steps":["trace[1243968898] 'process raft request' (duration: 459.772432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:59.843554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:59.382878Z","time spent":"460.608097ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:46:00.181161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.2659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:00.181235Z","caller":"traceutil/trace.go:171","msg":"trace[834321474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"315.363453ms","start":"2026-02-18T14:45:59.865851Z","end":"2026-02-18T14:46:00.181214Z","steps":["trace[834321474] 'range keys from in-memory index tree' (duration: 315.213018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:00.181273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:59.865839Z","time spent":"315.425915ms","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:46:01.561165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.903114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-18T14:46:01.561312Z","caller":"traceutil/trace.go:171","msg":"trace[401614145] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:991; }","duration":"129.086839ms","start":"2026-02-18T14:46:01.432206Z","end":"2026-02-18T14:46:01.561293Z","steps":["trace[401614145] 'range keys from in-memory index tree' (duration: 128.668996ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:01.737629Z","caller":"traceutil/trace.go:171","msg":"trace[1949737193] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"111.460091ms","start":"2026-02-18T14:46:01.626127Z","end":"2026-02-18T14:46:01.737588Z","steps":["trace[1949737193] 'process raft request' (duration: 35.716911ms)","trace[1949737193] 'compare' (duration: 75.511103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:03.611504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.764236ms","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-02-18T14:46:03.611881Z","caller":"traceutil/trace.go:171","msg":"trace[974157246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"304.892389ms","start":"2026-02-18T14:46:03.306676Z","end":"2026-02-18T14:46:03.611569Z","steps":["trace[974157246] 'range keys from in-memory index tree' (duration: 304.59594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:03.61188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.573193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-18T14:46:03.611933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:03.306661Z","time spent":"305.259251ms","remote":"127.0.0.1:36748","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-02-18T14:46:03.611939Z","caller":"traceutil/trace.go:171","msg":"trace[94735806] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1011; }","duration":"182.700647ms","start":"2026-02-18T14:46:03.429219Z","end":"2026-02-18T14:46:03.61192Z","steps":["trace[94735806] 'range keys from in-memory index tree' (duration: 182.172171ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:04.150449Z","caller":"traceutil/trace.go:171","msg":"trace[1040791578] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"162.703667ms","start":"2026-02-18T14:46:03.987728Z","end":"2026-02-18T14:46:04.150432Z","steps":["trace[1040791578] 'process raft request' (duration: 124.45153ms)","trace[1040791578] 'compare' (duration: 38.03355ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:09.791972Z","caller":"traceutil/trace.go:171","msg":"trace[1187014218] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"132.438529ms","start":"2026-02-18T14:46:09.659516Z","end":"2026-02-18T14:46:09.791954Z","steps":["trace[1187014218] 'process raft request' (duration: 132.313166ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:34.678721Z","caller":"traceutil/trace.go:171","msg":"trace[1055621249] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"136.103157ms","start":"2026-02-18T14:46:34.542592Z","end":"2026-02-18T14:46:34.678695Z","steps":["trace[1055621249] 'process raft request' (duration: 92.556842ms)","trace[1055621249] 'compare' (duration: 43.414851ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:43.127456Z","caller":"traceutil/trace.go:171","msg":"trace[830150726] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"122.41296ms","start":"2026-02-18T14:46:43.004998Z","end":"2026-02-18T14:46:43.127411Z","steps":["trace[830150726] 'process raft request' (duration: 58.244852ms)","trace[830150726] 'compare' (duration: 63.951552ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:43.354789Z","caller":"traceutil/trace.go:171","msg":"trace[1015576920] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"349.560944ms","start":"2026-02-18T14:46:43.005194Z","end":"2026-02-18T14:46:43.354755Z","steps":["trace[1015576920] 'process raft request' (duration: 290.521269ms)","trace[1015576920] 'compare' (duration: 58.765717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:43.354937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:43.005187Z","time spent":"349.669117ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:44.275585Z","caller":"traceutil/trace.go:171","msg":"trace[273720053] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"234.253286ms","start":"2026-02-18T14:46:44.041306Z","end":"2026-02-18T14:46:44.27556Z","steps":["trace[273720053] 'process raft request' (duration: 233.838403ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:44.726547Z","caller":"traceutil/trace.go:171","msg":"trace[1425533573] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"184.504886ms","start":"2026-02-18T14:46:44.542015Z","end":"2026-02-18T14:46:44.72652Z","steps":["trace[1425533573] 'process raft request' (duration: 184.359082ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:47.285711Z","caller":"traceutil/trace.go:171","msg":"trace[1395537341] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"143.674512ms","start":"2026-02-18T14:46:47.14201Z","end":"2026-02-18T14:46:47.285685Z","steps":["trace[1395537341] 'read index received' (duration: 113.629308ms)","trace[1395537341] 'applied index is now lower than readState.Index' (duration: 30.044494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:47.287975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.932959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:46:47.288002Z","caller":"traceutil/trace.go:171","msg":"trace[1931036523] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1196; }","duration":"146.023622ms","start":"2026-02-18T14:46:47.141971Z","end":"2026-02-18T14:46:47.287995Z","steps":["trace[1931036523] 'agreement among raft nodes before linearized reading' (duration: 145.932939ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:47.286036Z","caller":"traceutil/trace.go:171","msg":"trace[2007954448] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"151.145625ms","start":"2026-02-18T14:46:47.134879Z","end":"2026-02-18T14:46:47.286024Z","steps":["trace[2007954448] 'process raft request' (duration: 120.810943ms)","trace[2007954448] 'compare' (duration: 29.900069ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:54.462337Z","caller":"traceutil/trace.go:171","msg":"trace[7388645] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"113.393192ms","start":"2026-02-18T14:46:54.348913Z","end":"2026-02-18T14:46:54.462307Z","steps":["trace[7388645] 'process raft request' (duration: 112.710372ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:18.788546Z","caller":"traceutil/trace.go:171","msg":"trace[2116574620] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"107.63746ms","start":"2026-02-18T14:47:18.680885Z","end":"2026-02-18T14:47:18.788522Z","steps":["trace[2116574620] 'process raft request' (duration: 51.11723ms)","trace[2116574620] 'compare' (duration: 56.132379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:59.4565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.84617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:59.45678Z","caller":"traceutil/trace.go:171","msg":"trace[423566318] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"169.204974ms","start":"2026-02-18T14:48:59.287546Z","end":"2026-02-18T14:48:59.456751Z","steps":["trace[423566318] 'process raft request' (duration: 36.024314ms)","trace[423566318] 'compare' (duration: 132.670655ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:19.418106Z","caller":"traceutil/trace.go:171","msg":"trace[569097701] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"105.946806ms","start":"2026-02-18T14:49:19.312133Z","end":"2026-02-18T14:49:19.418079Z","steps":["trace[569097701] 'read index received' (duration: 105.754871ms)","trace[569097701] 'applied index is now lower than readState.Index' (duration: 190.315µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:19.418482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.337358ms","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-02-18T14:49:19.418563Z","caller":"traceutil/trace.go:171","msg":"trace[1943017766] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1845; }","duration":"106.474703ms","start":"2026-02-18T14:49:19.312075Z","end":"2026-02-18T14:49:19.41855Z","steps":["trace[1943017766] 'agreement among raft nodes before linearized reading' (duration: 106.234175ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:19.419067Z","caller":"traceutil/trace.go:171","msg":"trace[1755517355] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"126.456634ms","start":"2026-02-18T14:49:19.292593Z","end":"2026-02-18T14:49:19.41905Z","steps":["trace[1755517355] 'process raft request' (duration: 125.35504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:45.008918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.64063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7027"} {"level":"info","ts":"2026-02-18T14:49:45.008982Z","caller":"traceutil/trace.go:171","msg":"trace[1820833498] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1958; }","duration":"263.738723ms","start":"2026-02-18T14:49:44.74523Z","end":"2026-02-18T14:49:45.008969Z","steps":["trace[1820833498] 'range keys from in-memory index tree' (duration: 263.562948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:45.009167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.901667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5078"} {"level":"info","ts":"2026-02-18T14:49:45.009197Z","caller":"traceutil/trace.go:171","msg":"trace[51743552] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1958; }","duration":"251.948159ms","start":"2026-02-18T14:49:44.75724Z","end":"2026-02-18T14:49:45.009189Z","steps":["trace[51743552] 'range keys from in-memory index tree' (duration: 251.858686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:46.094966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.241694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:790"} {"level":"info","ts":"2026-02-18T14:49:46.095268Z","caller":"traceutil/trace.go:171","msg":"trace[997540612] range","detail":"{range_begin:/registry/rolebindings/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:1961; }","duration":"106.577535ms","start":"2026-02-18T14:49:45.988674Z","end":"2026-02-18T14:49:46.095252Z","steps":["trace[997540612] 'range keys from in-memory index tree' (duration: 106.121661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:46.095143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.936695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:951"} {"level":"info","ts":"2026-02-18T14:49:46.095719Z","caller":"traceutil/trace.go:171","msg":"trace[911940422] range","detail":"{range_begin:/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1961; }","duration":"138.507862ms","start":"2026-02-18T14:49:45.957167Z","end":"2026-02-18T14:49:46.095675Z","steps":["trace[911940422] 'range keys from in-memory index tree' (duration: 137.662626ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:50.773973Z","caller":"traceutil/trace.go:171","msg":"trace[1369009720] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"237.604126ms","start":"2026-02-18T14:49:50.53635Z","end":"2026-02-18T14:49:50.773954Z","steps":["trace[1369009720] 'read index received' (duration: 237.503273ms)","trace[1369009720] 'applied index is now lower than readState.Index' (duration: 99.543µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:50.774222Z","caller":"traceutil/trace.go:171","msg":"trace[1228168563] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"243.23638ms","start":"2026-02-18T14:49:50.530971Z","end":"2026-02-18T14:49:50.774207Z","steps":["trace[1228168563] 'process raft request' (duration: 242.864538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:50.775093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.73016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:120 size:92149"} {"level":"info","ts":"2026-02-18T14:49:50.775186Z","caller":"traceutil/trace.go:171","msg":"trace[1645526740] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:120; response_revision:1981; }","duration":"238.845444ms","start":"2026-02-18T14:49:50.536328Z","end":"2026-02-18T14:49:50.775173Z","steps":["trace[1645526740] 'agreement among raft nodes before linearized reading' (duration: 238.175193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:50.801258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.166008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T14:49:50.801316Z","caller":"traceutil/trace.go:171","msg":"trace[1805176045] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1982; }","duration":"233.294532ms","start":"2026-02-18T14:49:50.568007Z","end":"2026-02-18T14:49:50.801302Z","steps":["trace[1805176045] 'agreement among raft nodes before linearized reading' (duration: 233.178619ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:50.801565Z","caller":"traceutil/trace.go:171","msg":"trace[660284133] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"113.051465ms","start":"2026-02-18T14:49:50.688506Z","end":"2026-02-18T14:49:50.801558Z","steps":["trace[660284133] 'process raft request' (duration: 112.574081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:50.801691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.716264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T14:49:50.801708Z","caller":"traceutil/trace.go:171","msg":"trace[139006852] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1982; }","duration":"144.770496ms","start":"2026-02-18T14:49:50.656933Z","end":"2026-02-18T14:49:50.801703Z","steps":["trace[139006852] 'agreement among raft nodes before linearized reading' (duration: 144.702374ms)"],"step_count":1}