{"level":"warn","ts":"2026-02-25T17:03:57.550296Z","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-25T17:03:57.550382Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.137:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.137:2380","--initial-cluster=instance=https://199.19.213.137:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.137:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.137: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-25T17:03:57.55045Z","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-25T17:03:57.550464Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-02-25T17:03:57.550488Z","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-25T17:03:57.551095Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"]} {"level":"info","ts":"2026-02-25T17:03:57.551172Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.137: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-25T17:03:57.576356Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"24.970972ms"} {"level":"info","ts":"2026-02-25T17:03:57.635771Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2ba8e3c2b0588654","cluster-id":"97694c3f9947e4ca"} {"level":"info","ts":"2026-02-25T17:03:57.635931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:03:57.636032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 0"} {"level":"info","ts":"2026-02-25T17:03:57.636064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2ba8e3c2b0588654 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:03:57.636202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 1"} {"level":"info","ts":"2026-02-25T17:03:57.636307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"warn","ts":"2026-02-25T17:03:57.681708Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:03:57.699588Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:03:57.713367Z","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-25T17:03:57.749359Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2ba8e3c2b0588654","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:03:57.749629Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2ba8e3c2b0588654","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:03:57.749666Z","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-25T17:03:57.75018Z","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-25T17:03:57.75028Z","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-25T17:03:57.757842Z","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-25T17:03:57.757928Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-02-25T17:03:57.757957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-02-25T17:03:57.758569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2ba8e3c2b0588654","initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:03:57.758601Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:03:57.759773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"info","ts":"2026-02-25T17:03:57.759868Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","added-peer-id":"2ba8e3c2b0588654","added-peer-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-02-25T17:03:57.937308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:03:57.937359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:03:57.937384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgPreVoteResp from 2ba8e3c2b0588654 at term 1"} {"level":"info","ts":"2026-02-25T17:03:57.93752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:03:57.93754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgVoteResp from 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-02-25T17:03:57.937603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became leader at term 2"} {"level":"info","ts":"2026-02-25T17:03:57.937651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2ba8e3c2b0588654 elected leader 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-02-25T17:03:57.952685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2ba8e3c2b0588654","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.137:2379]}","request-path":"/0/members/2ba8e3c2b0588654/attributes","cluster-id":"97694c3f9947e4ca","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:03:57.952738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:03:57.952755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:57.952848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:03:57.953317Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:03:57.953336Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:03:57.956115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:03:57.957127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.137:2379"} {"level":"info","ts":"2026-02-25T17:03:57.96126Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:57.961454Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:57.961517Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:04.504471Z","caller":"traceutil/trace.go:171","msg":"trace[2084507950] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"110.464925ms","start":"2026-02-25T17:04:04.393984Z","end":"2026-02-25T17:04:04.504449Z","steps":["trace[2084507950] 'process raft request' (duration: 110.161149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.446435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.257183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-25T17:04:08.44655Z","caller":"traceutil/trace.go:171","msg":"trace[311182670] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:335; }","duration":"290.414735ms","start":"2026-02-25T17:04:08.156121Z","end":"2026-02-25T17:04:08.446535Z","steps":["trace[311182670] 'range keys from in-memory index tree' (duration: 290.112429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.446535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.005111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:04:08.446826Z","caller":"traceutil/trace.go:171","msg":"trace[2119995054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:335; }","duration":"352.349899ms","start":"2026-02-25T17:04:08.094447Z","end":"2026-02-25T17:04:08.446797Z","steps":["trace[2119995054] 'range keys from in-memory index tree' (duration: 351.881848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.44715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:08.094426Z","time spent":"352.592554ms","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-25T17:04:13.326254Z","caller":"traceutil/trace.go:171","msg":"trace[1390636073] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"108.857199ms","start":"2026-02-25T17:04:13.217371Z","end":"2026-02-25T17:04:13.326228Z","steps":["trace[1390636073] 'process raft request' (duration: 108.641363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.108183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.124414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-25T17:04:14.108288Z","caller":"traceutil/trace.go:171","msg":"trace[347977048] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:376; }","duration":"104.301658ms","start":"2026-02-25T17:04:14.003959Z","end":"2026-02-25T17:04:14.108261Z","steps":["trace[347977048] 'range keys from in-memory index tree' (duration: 103.908628ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.025897Z","caller":"traceutil/trace.go:171","msg":"trace[793818865] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"159.855027ms","start":"2026-02-25T17:04:16.866008Z","end":"2026-02-25T17:04:17.025863Z","steps":["trace[793818865] 'process raft request' (duration: 159.638793ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.985633Z","caller":"traceutil/trace.go:171","msg":"trace[34468691] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"176.897311ms","start":"2026-02-25T17:04:17.808709Z","end":"2026-02-25T17:04:17.985606Z","steps":["trace[34468691] 'process raft request' (duration: 176.746717ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:22.176227Z","caller":"traceutil/trace.go:171","msg":"trace[708560922] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"166.817684ms","start":"2026-02-25T17:04:22.009388Z","end":"2026-02-25T17:04:22.176205Z","steps":["trace[708560922] 'process raft request' (duration: 166.703531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.195353Z","caller":"traceutil/trace.go:171","msg":"trace[1820029970] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"141.028893ms","start":"2026-02-25T17:04:23.054296Z","end":"2026-02-25T17:04:23.195325Z","steps":["trace[1820029970] 'process raft request' (duration: 140.87315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.621624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.416342ms","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-25T17:04:23.621727Z","caller":"traceutil/trace.go:171","msg":"trace[677613221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"209.595426ms","start":"2026-02-25T17:04:23.412106Z","end":"2026-02-25T17:04:23.621702Z","steps":["trace[677613221] 'range keys from in-memory index tree' (duration: 209.238119ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:29.336126Z","caller":"traceutil/trace.go:171","msg":"trace[491856587] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"120.85701ms","start":"2026-02-25T17:04:29.215243Z","end":"2026-02-25T17:04:29.3361Z","steps":["trace[491856587] 'read index received' (duration: 120.580643ms)","trace[491856587] 'applied index is now lower than readState.Index' (duration: 275.227µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:29.336221Z","caller":"traceutil/trace.go:171","msg":"trace[1593280825] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"755.506601ms","start":"2026-02-25T17:04:28.580676Z","end":"2026-02-25T17:04:29.336182Z","steps":["trace[1593280825] 'process raft request' (duration: 755.119402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:29.33643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.155957ms","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-25T17:04:29.33652Z","caller":"traceutil/trace.go:171","msg":"trace[1667011916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"121.3054ms","start":"2026-02-25T17:04:29.215193Z","end":"2026-02-25T17:04:29.336498Z","steps":["trace[1667011916] 'agreement among raft nodes before linearized reading' (duration: 121.018664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:29.337002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:28.580655Z","time spent":"755.673545ms","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:33.563033Z","caller":"traceutil/trace.go:171","msg":"trace[1075000994] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"137.915473ms","start":"2026-02-25T17:04:33.425084Z","end":"2026-02-25T17:04:33.563Z","steps":["trace[1075000994] 'process raft request' (duration: 137.737699ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:34.119231Z","caller":"traceutil/trace.go:171","msg":"trace[1704780591] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"306.586159ms","start":"2026-02-25T17:04:33.812612Z","end":"2026-02-25T17:04:34.119198Z","steps":["trace[1704780591] 'process raft request' (duration: 306.377114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:34.119523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.812586Z","time spent":"306.745752ms","remote":"127.0.0.1:45682","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-25T17:04:34.126532Z","caller":"traceutil/trace.go:171","msg":"trace[550042507] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"253.111217ms","start":"2026-02-25T17:04:33.873382Z","end":"2026-02-25T17:04:34.126493Z","steps":["trace[550042507] 'process raft request' (duration: 252.897551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:36.893374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.582988ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:04:37.997475Z","caller":"traceutil/trace.go:171","msg":"trace[352703547] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"126.789473ms","start":"2026-02-25T17:04:37.87066Z","end":"2026-02-25T17:04:37.99745Z","steps":["trace[352703547] 'process raft request' (duration: 126.618309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:39.148106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016971394s","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-25T17:04:39.148168Z","caller":"traceutil/trace.go:171","msg":"trace[1651831539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"1.017042466s","start":"2026-02-25T17:04:38.131111Z","end":"2026-02-25T17:04:39.148153Z","steps":["trace[1651831539] 'range keys from in-memory index tree' (duration: 1.016834762s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:39.148127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.964654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T17:04:39.148198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:38.131071Z","time spent":"1.017119828s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:04:39.14825Z","caller":"traceutil/trace.go:171","msg":"trace[1322318806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"897.124838ms","start":"2026-02-25T17:04:38.251099Z","end":"2026-02-25T17:04:39.148223Z","steps":["trace[1322318806] 'range keys from in-memory index tree' (duration: 896.730659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:39.148327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:38.251077Z","time spent":"897.2328ms","remote":"127.0.0.1:45682","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-25T17:04:39.282891Z","caller":"traceutil/trace.go:171","msg":"trace[350324698] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"130.621909ms","start":"2026-02-25T17:04:39.152229Z","end":"2026-02-25T17:04:39.282851Z","steps":["trace[350324698] 'process raft request' (duration: 130.403234ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:39.299875Z","caller":"traceutil/trace.go:171","msg":"trace[754837822] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"120.217465ms","start":"2026-02-25T17:04:39.179635Z","end":"2026-02-25T17:04:39.299852Z","steps":["trace[754837822] 'read index received' (duration: 102.992108ms)","trace[754837822] 'applied index is now lower than readState.Index' (duration: 17.224057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:39.300023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.371828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:39.300061Z","caller":"traceutil/trace.go:171","msg":"trace[1052798319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"120.467741ms","start":"2026-02-25T17:04:39.179586Z","end":"2026-02-25T17:04:39.300054Z","steps":["trace[1052798319] 'agreement among raft nodes before linearized reading' (duration: 120.352648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:39.300244Z","caller":"traceutil/trace.go:171","msg":"trace[1853704222] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"147.848086ms","start":"2026-02-25T17:04:39.152346Z","end":"2026-02-25T17:04:39.300195Z","steps":["trace[1853704222] 'process raft request' (duration: 147.354405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.30218Z","caller":"traceutil/trace.go:171","msg":"trace[44382649] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"111.53224ms","start":"2026-02-25T17:04:41.190625Z","end":"2026-02-25T17:04:41.302157Z","steps":["trace[44382649] 'process raft request' (duration: 38.272901ms)","trace[44382649] 'compare' (duration: 73.166067ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:41.33483Z","caller":"traceutil/trace.go:171","msg":"trace[1470208618] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"135.787127ms","start":"2026-02-25T17:04:41.199021Z","end":"2026-02-25T17:04:41.334808Z","steps":["trace[1470208618] 'process raft request' (duration: 135.675884ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.485211Z","caller":"traceutil/trace.go:171","msg":"trace[1751912354] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"143.291165ms","start":"2026-02-25T17:04:41.341896Z","end":"2026-02-25T17:04:41.485187Z","steps":["trace[1751912354] 'process raft request' (duration: 143.228163ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.485281Z","caller":"traceutil/trace.go:171","msg":"trace[396402658] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"144.583173ms","start":"2026-02-25T17:04:41.340665Z","end":"2026-02-25T17:04:41.485248Z","steps":["trace[396402658] 'process raft request' (duration: 109.273649ms)","trace[396402658] 'compare' (duration: 34.986027ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:41.488578Z","caller":"traceutil/trace.go:171","msg":"trace[2042796782] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"139.069699ms","start":"2026-02-25T17:04:41.349483Z","end":"2026-02-25T17:04:41.488552Z","steps":["trace[2042796782] 'process raft request' (duration: 137.455502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.488795Z","caller":"traceutil/trace.go:171","msg":"trace[1700565210] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"138.751453ms","start":"2026-02-25T17:04:41.35002Z","end":"2026-02-25T17:04:41.488772Z","steps":["trace[1700565210] 'process raft request' (duration: 138.228241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.489372Z","caller":"traceutil/trace.go:171","msg":"trace[65969303] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"138.19004ms","start":"2026-02-25T17:04:41.350203Z","end":"2026-02-25T17:04:41.488394Z","steps":["trace[65969303] 'process raft request' (duration: 138.133839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.029787Z","caller":"traceutil/trace.go:171","msg":"trace[1341622493] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"374.436606ms","start":"2026-02-25T17:04:41.655332Z","end":"2026-02-25T17:04:42.029769Z","steps":["trace[1341622493] 'read index received' (duration: 8.633204ms)","trace[1341622493] 'applied index is now lower than readState.Index' (duration: 365.802642ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:42.029812Z","caller":"traceutil/trace.go:171","msg":"trace[1811165785] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"391.708324ms","start":"2026-02-25T17:04:41.638074Z","end":"2026-02-25T17:04:42.029782Z","steps":["trace[1811165785] 'process raft request' (duration: 351.674603ms)","trace[1811165785] 'compare' (duration: 39.867948ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:42.029863Z","caller":"traceutil/trace.go:171","msg":"trace[1906635815] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"380.258898ms","start":"2026-02-25T17:04:41.649581Z","end":"2026-02-25T17:04:42.02984Z","steps":["trace[1906635815] 'process raft request' (duration: 380.150115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.029966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.62274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T17:04:42.029963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.638029Z","time spent":"391.844937ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:42.029994Z","caller":"traceutil/trace.go:171","msg":"trace[263990542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"374.688842ms","start":"2026-02-25T17:04:41.655298Z","end":"2026-02-25T17:04:42.029987Z","steps":["trace[263990542] 'agreement among raft nodes before linearized reading' (duration: 374.559489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.030015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.655288Z","time spent":"374.722312ms","remote":"127.0.0.1:45682","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-25T17:04:42.030029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.64956Z","time spent":"380.40087ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:42.042138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.360221ms","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-25T17:04:42.042227Z","caller":"traceutil/trace.go:171","msg":"trace[846722353] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:540; }","duration":"109.516744ms","start":"2026-02-25T17:04:41.932691Z","end":"2026-02-25T17:04:42.042207Z","steps":["trace[846722353] 'agreement among raft nodes before linearized reading' (duration: 109.356471ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.042468Z","caller":"traceutil/trace.go:171","msg":"trace[695670423] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"306.955447ms","start":"2026-02-25T17:04:41.735223Z","end":"2026-02-25T17:04:42.042178Z","steps":["trace[695670423] 'process raft request' (duration: 306.626089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.042749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.7352Z","time spent":"307.483009ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:42.273135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.606623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:42.273341Z","caller":"traceutil/trace.go:171","msg":"trace[2049331357] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"211.910459ms","start":"2026-02-25T17:04:42.061299Z","end":"2026-02-25T17:04:42.27321Z","steps":["trace[2049331357] 'process raft request' (duration: 96.149404ms)","trace[2049331357] 'compare' (duration: 115.462619ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:42.273463Z","caller":"traceutil/trace.go:171","msg":"trace[333215278] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"135.782356ms","start":"2026-02-25T17:04:42.137664Z","end":"2026-02-25T17:04:42.273446Z","steps":["trace[333215278] 'process raft request' (duration: 135.723995ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.273464Z","caller":"traceutil/trace.go:171","msg":"trace[100889874] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"139.124361ms","start":"2026-02-25T17:04:42.134323Z","end":"2026-02-25T17:04:42.273447Z","steps":["trace[100889874] 'process raft request' (duration: 138.977008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:44.129528Z","caller":"traceutil/trace.go:171","msg":"trace[562674178] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"195.437267ms","start":"2026-02-25T17:04:43.934064Z","end":"2026-02-25T17:04:44.129502Z","steps":["trace[562674178] 'read index received' (duration: 180.528091ms)","trace[562674178] 'applied index is now lower than readState.Index' (duration: 14.908046ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:44.129613Z","caller":"traceutil/trace.go:171","msg":"trace[1037091898] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"393.983865ms","start":"2026-02-25T17:04:43.735596Z","end":"2026-02-25T17:04:44.12958Z","steps":["trace[1037091898] 'process raft request' (duration: 379.08498ms)","trace[1037091898] 'compare' (duration: 14.693331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:44.129743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.735568Z","time spent":"394.110767ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:44.129918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.818096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-25T17:04:44.129998Z","caller":"traceutil/trace.go:171","msg":"trace[1386839197] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"195.975629ms","start":"2026-02-25T17:04:43.933996Z","end":"2026-02-25T17:04:44.129971Z","steps":["trace[1386839197] 'agreement among raft nodes before linearized reading' (duration: 195.645222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.538568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.000774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:44.538696Z","caller":"traceutil/trace.go:171","msg":"trace[962344448] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"359.220963ms","start":"2026-02-25T17:04:44.179463Z","end":"2026-02-25T17:04:44.538684Z","steps":["trace[962344448] 'read index received' (duration: 141.060575ms)","trace[962344448] 'applied index is now lower than readState.Index' (duration: 218.159748ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:44.538738Z","caller":"traceutil/trace.go:171","msg":"trace[951451572] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"398.296892ms","start":"2026-02-25T17:04:44.140436Z","end":"2026-02-25T17:04:44.538733Z","steps":["trace[951451572] 'process raft request' (duration: 398.20574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.538779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:44.140403Z","time spent":"398.350074ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:44.538803Z","caller":"traceutil/trace.go:171","msg":"trace[25622492] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"400.062742ms","start":"2026-02-25T17:04:44.138705Z","end":"2026-02-25T17:04:44.538768Z","steps":["trace[25622492] 'process raft request' (duration: 181.808712ms)","trace[25622492] 'compare' (duration: 217.876142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:44.538834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.339676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:44.538921Z","caller":"traceutil/trace.go:171","msg":"trace[1905134717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"359.462089ms","start":"2026-02-25T17:04:44.179433Z","end":"2026-02-25T17:04:44.538895Z","steps":["trace[1905134717] 'agreement among raft nodes before linearized reading' (duration: 359.297695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.538961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:44.17942Z","time spent":"359.53381ms","remote":"127.0.0.1:55332","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-25T17:04:44.539125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:44.138675Z","time spent":"400.204054ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:44.540463Z","caller":"traceutil/trace.go:171","msg":"trace[155732993] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"178.546558ms","start":"2026-02-25T17:04:44.361896Z","end":"2026-02-25T17:04:44.540443Z","steps":["trace[155732993] 'process raft request' (duration: 178.164589ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:44.56609Z","caller":"traceutil/trace.go:171","msg":"trace[1826752962] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"201.639258ms","start":"2026-02-25T17:04:44.364432Z","end":"2026-02-25T17:04:44.566071Z","steps":["trace[1826752962] 'process raft request' (duration: 201.495674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.566326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.553903ms","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-25T17:04:44.566355Z","caller":"traceutil/trace.go:171","msg":"trace[1557135072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"288.631645ms","start":"2026-02-25T17:04:44.277716Z","end":"2026-02-25T17:04:44.566347Z","steps":["trace[1557135072] 'agreement among raft nodes before linearized reading' (duration: 288.512432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:47.230449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.390224ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:04:47.230611Z","caller":"traceutil/trace.go:171","msg":"trace[1598535091] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"251.593261ms","start":"2026-02-25T17:04:46.978996Z","end":"2026-02-25T17:04:47.23059Z","steps":["trace[1598535091] 'read index received' (duration: 48.851µs)","trace[1598535091] 'applied index is now lower than readState.Index' (duration: 251.54281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:47.231359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.326219ms","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-25T17:04:47.231402Z","caller":"traceutil/trace.go:171","msg":"trace[900276590] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:588; }","duration":"252.440392ms","start":"2026-02-25T17:04:46.978948Z","end":"2026-02-25T17:04:47.231389Z","steps":["trace[900276590] 'agreement among raft nodes before linearized reading' (duration: 251.735825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:47.644161Z","caller":"traceutil/trace.go:171","msg":"trace[633061269] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"337.258458ms","start":"2026-02-25T17:04:47.306881Z","end":"2026-02-25T17:04:47.644139Z","steps":["trace[633061269] 'read index received' (duration: 337.017953ms)","trace[633061269] 'applied index is now lower than readState.Index' (duration: 239.755µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:47.644266Z","caller":"traceutil/trace.go:171","msg":"trace[2096803129] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"408.42137ms","start":"2026-02-25T17:04:47.235811Z","end":"2026-02-25T17:04:47.644232Z","steps":["trace[2096803129] 'process raft request' (duration: 408.160995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:47.644389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.478913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x5525\" ","response":"range_response_count:1 size:18075"} {"level":"warn","ts":"2026-02-25T17:04:47.644429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.235794Z","time spent":"408.543014ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:47.644456Z","caller":"traceutil/trace.go:171","msg":"trace[70063347] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x5525; range_end:; response_count:1; response_revision:589; }","duration":"337.584316ms","start":"2026-02-25T17:04:47.306854Z","end":"2026-02-25T17:04:47.644438Z","steps":["trace[70063347] 'agreement among raft nodes before linearized reading' (duration: 337.366941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:47.644495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.30684Z","time spent":"337.645437ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18098,"request content":"key:\"/registry/pods/kube-system/cilium-x5525\" "} {"level":"warn","ts":"2026-02-25T17:04:47.947928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.027294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:47.948436Z","caller":"traceutil/trace.go:171","msg":"trace[420346500] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"291.96622ms","start":"2026-02-25T17:04:47.656212Z","end":"2026-02-25T17:04:47.948179Z","steps":["trace[420346500] 'process raft request' (duration: 291.884328ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:47.948404Z","caller":"traceutil/trace.go:171","msg":"trace[1711312359] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"289.321101ms","start":"2026-02-25T17:04:47.659053Z","end":"2026-02-25T17:04:47.948374Z","steps":["trace[1711312359] 'process raft request' (duration: 289.090515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:47.948425Z","caller":"traceutil/trace.go:171","msg":"trace[1391333099] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"286.907706ms","start":"2026-02-25T17:04:47.661487Z","end":"2026-02-25T17:04:47.948395Z","steps":["trace[1391333099] 'process raft request' (duration: 286.769872ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:47.948748Z","caller":"traceutil/trace.go:171","msg":"trace[1329680063] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"292.87477ms","start":"2026-02-25T17:04:47.655351Z","end":"2026-02-25T17:04:47.948226Z","steps":["trace[1329680063] 'process raft request' (duration: 292.669645ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:47.948222Z","caller":"traceutil/trace.go:171","msg":"trace[424255498] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"293.540254ms","start":"2026-02-25T17:04:47.654583Z","end":"2026-02-25T17:04:47.948123Z","steps":["trace[424255498] 'process raft request' (duration: 128.205144ms)","trace[424255498] 'compare' (duration: 164.898811ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:48.337128Z","caller":"traceutil/trace.go:171","msg":"trace[1748020904] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"155.99868ms","start":"2026-02-25T17:04:48.181106Z","end":"2026-02-25T17:04:48.337105Z","steps":["trace[1748020904] 'read index received' (duration: 155.914828ms)","trace[1748020904] 'applied index is now lower than readState.Index' (duration: 83.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:48.337171Z","caller":"traceutil/trace.go:171","msg":"trace[386032431] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"375.762096ms","start":"2026-02-25T17:04:47.961389Z","end":"2026-02-25T17:04:48.337151Z","steps":["trace[386032431] 'process raft request' (duration: 375.567711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.337263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.140963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:48.337295Z","caller":"traceutil/trace.go:171","msg":"trace[1224848273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"156.235036ms","start":"2026-02-25T17:04:48.181049Z","end":"2026-02-25T17:04:48.337284Z","steps":["trace[1224848273] 'agreement among raft nodes before linearized reading' (duration: 156.172574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.337506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.707458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T17:04:48.337525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.96137Z","time spent":"375.857237ms","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:48.337567Z","caller":"traceutil/trace.go:171","msg":"trace[205095959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"149.80902ms","start":"2026-02-25T17:04:48.18774Z","end":"2026-02-25T17:04:48.337549Z","steps":["trace[205095959] 'agreement among raft nodes before linearized reading' (duration: 149.630526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.620614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.081551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:48.620721Z","caller":"traceutil/trace.go:171","msg":"trace[1181698193] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"278.039388ms","start":"2026-02-25T17:04:48.342663Z","end":"2026-02-25T17:04:48.620702Z","steps":["trace[1181698193] 'process raft request' (duration: 101.796792ms)","trace[1181698193] 'compare' (duration: 175.968069ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.042692Z","caller":"traceutil/trace.go:171","msg":"trace[361656055] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"325.133207ms","start":"2026-02-25T17:04:48.717542Z","end":"2026-02-25T17:04:49.042676Z","steps":["trace[361656055] 'process raft request' (duration: 325.017804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.042791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.717518Z","time spent":"325.229058ms","remote":"127.0.0.1:36542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:49.050862Z","caller":"traceutil/trace.go:171","msg":"trace[816447172] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"162.894406ms","start":"2026-02-25T17:04:48.88795Z","end":"2026-02-25T17:04:49.050844Z","steps":["trace[816447172] 'process raft request' (duration: 162.824734ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.050904Z","caller":"traceutil/trace.go:171","msg":"trace[1387431390] transaction","detail":"{read_only:false; number_of_response:0; response_revision:598; }","duration":"333.226928ms","start":"2026-02-25T17:04:48.717648Z","end":"2026-02-25T17:04:49.050875Z","steps":["trace[1387431390] 'process raft request' (duration: 333.003743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.051069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.717606Z","time spent":"333.361311ms","remote":"127.0.0.1:36542","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:49.156339Z","caller":"traceutil/trace.go:171","msg":"trace[694224382] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"103.481099ms","start":"2026-02-25T17:04:49.052838Z","end":"2026-02-25T17:04:49.156319Z","steps":["trace[694224382] 'read index received' (duration: 103.288384ms)","trace[694224382] 'applied index is now lower than readState.Index' (duration: 192.095µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.156433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.586342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1889"} {"level":"info","ts":"2026-02-25T17:04:49.156456Z","caller":"traceutil/trace.go:171","msg":"trace[1516598751] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:600; }","duration":"103.648412ms","start":"2026-02-25T17:04:49.052799Z","end":"2026-02-25T17:04:49.156448Z","steps":["trace[1516598751] 'agreement among raft nodes before linearized reading' (duration: 103.591251ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.156429Z","caller":"traceutil/trace.go:171","msg":"trace[1583376356] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"104.336868ms","start":"2026-02-25T17:04:49.052061Z","end":"2026-02-25T17:04:49.156398Z","steps":["trace[1583376356] 'process raft request' (duration: 104.135424ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.832186Z","caller":"traceutil/trace.go:171","msg":"trace[1091063295] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"115.052489ms","start":"2026-02-25T17:04:49.717109Z","end":"2026-02-25T17:04:49.832161Z","steps":["trace[1091063295] 'read index received' (duration: 114.766123ms)","trace[1091063295] 'applied index is now lower than readState.Index' (duration: 285.246µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.832422Z","caller":"traceutil/trace.go:171","msg":"trace[120279837] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"120.027981ms","start":"2026-02-25T17:04:49.712376Z","end":"2026-02-25T17:04:49.832404Z","steps":["trace[120279837] 'process raft request' (duration: 119.571311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.832641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.512899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.832694Z","caller":"traceutil/trace.go:171","msg":"trace[884204268] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:603; }","duration":"115.58229ms","start":"2026-02-25T17:04:49.717097Z","end":"2026-02-25T17:04:49.83268Z","steps":["trace[884204268] 'agreement among raft nodes before linearized reading' (duration: 115.485808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.832649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.763242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.832821Z","caller":"traceutil/trace.go:171","msg":"trace[455989041] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:603; }","duration":"114.993727ms","start":"2026-02-25T17:04:49.717802Z","end":"2026-02-25T17:04:49.832796Z","steps":["trace[455989041] 'agreement among raft nodes before linearized reading' (duration: 114.751732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.832977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.961197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.833069Z","caller":"traceutil/trace.go:171","msg":"trace[2135767426] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:603; }","duration":"115.173551ms","start":"2026-02-25T17:04:49.717869Z","end":"2026-02-25T17:04:49.833042Z","steps":["trace[2135767426] 'agreement among raft nodes before linearized reading' (duration: 114.807743ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.025662Z","caller":"traceutil/trace.go:171","msg":"trace[1170535426] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"125.600576ms","start":"2026-02-25T17:04:50.900042Z","end":"2026-02-25T17:04:51.025643Z","steps":["trace[1170535426] 'process raft request' (duration: 125.483834ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.153635Z","caller":"traceutil/trace.go:171","msg":"trace[1143272253] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"110.272502ms","start":"2026-02-25T17:04:51.043334Z","end":"2026-02-25T17:04:51.153606Z","steps":["trace[1143272253] 'process raft request' (duration: 109.393142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.608904Z","caller":"traceutil/trace.go:171","msg":"trace[1063793428] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"448.39901ms","start":"2026-02-25T17:04:51.160484Z","end":"2026-02-25T17:04:51.608883Z","steps":["trace[1063793428] 'process raft request' (duration: 403.378687ms)","trace[1063793428] 'compare' (duration: 44.829029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.609015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:51.160462Z","time spent":"448.494283ms","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4088,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:51.614687Z","caller":"traceutil/trace.go:171","msg":"trace[812532364] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"435.119961ms","start":"2026-02-25T17:04:51.179552Z","end":"2026-02-25T17:04:51.614672Z","steps":["trace[812532364] 'read index received' (duration: 384.319128ms)","trace[812532364] 'applied index is now lower than readState.Index' (duration: 50.800063ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:51.614897Z","caller":"traceutil/trace.go:171","msg":"trace[2008100402] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"448.919522ms","start":"2026-02-25T17:04:51.165968Z","end":"2026-02-25T17:04:51.614888Z","steps":["trace[2008100402] 'process raft request' (duration: 448.564964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.614976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:51.165946Z","time spent":"448.975522ms","remote":"127.0.0.1:45866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:51.615116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.561711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:51.615151Z","caller":"traceutil/trace.go:171","msg":"trace[1417860493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"435.630242ms","start":"2026-02-25T17:04:51.179511Z","end":"2026-02-25T17:04:51.615141Z","steps":["trace[1417860493] 'agreement among raft nodes before linearized reading' (duration: 435.581141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.615171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:51.179492Z","time spent":"435.673973ms","remote":"127.0.0.1:55340","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-25T17:04:51.620158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.599469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.137\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:04:51.620219Z","caller":"traceutil/trace.go:171","msg":"trace[1058871138] range","detail":"{range_begin:/registry/masterleases/199.19.213.137; range_end:; response_count:1; response_revision:613; }","duration":"342.699722ms","start":"2026-02-25T17:04:51.277505Z","end":"2026-02-25T17:04:51.620205Z","steps":["trace[1058871138] 'agreement among raft nodes before linearized reading' (duration: 342.517158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.620249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:51.277477Z","time spent":"342.766002ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.137\" "} {"level":"info","ts":"2026-02-25T17:04:54.550355Z","caller":"traceutil/trace.go:171","msg":"trace[1719948498] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"132.214996ms","start":"2026-02-25T17:04:54.418098Z","end":"2026-02-25T17:04:54.550313Z","steps":["trace[1719948498] 'process raft request' (duration: 131.934119ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:54.550566Z","caller":"traceutil/trace.go:171","msg":"trace[352472851] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"130.424645ms","start":"2026-02-25T17:04:54.420119Z","end":"2026-02-25T17:04:54.550544Z","steps":["trace[352472851] 'process raft request' (duration: 130.035676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:54.551056Z","caller":"traceutil/trace.go:171","msg":"trace[733299101] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"129.73352ms","start":"2026-02-25T17:04:54.421305Z","end":"2026-02-25T17:04:54.551039Z","steps":["trace[733299101] 'process raft request' (duration: 129.608647ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:54.551084Z","caller":"traceutil/trace.go:171","msg":"trace[602060386] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"130.952106ms","start":"2026-02-25T17:04:54.420118Z","end":"2026-02-25T17:04:54.55107Z","steps":["trace[602060386] 'process raft request' (duration: 130.308922ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:54.551355Z","caller":"traceutil/trace.go:171","msg":"trace[1796418495] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:681; }","duration":"133.150427ms","start":"2026-02-25T17:04:54.418191Z","end":"2026-02-25T17:04:54.551341Z","steps":["trace[1796418495] 'read index received' (duration: 75.036249ms)","trace[1796418495] 'applied index is now lower than readState.Index' (duration: 58.112728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:54.552882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.665681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-h4m45\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-02-25T17:04:54.552937Z","caller":"traceutil/trace.go:171","msg":"trace[1802378136] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-h4m45; range_end:; response_count:1; response_revision:663; }","duration":"134.756542ms","start":"2026-02-25T17:04:54.418164Z","end":"2026-02-25T17:04:54.55292Z","steps":["trace[1802378136] 'agreement among raft nodes before linearized reading' (duration: 133.2904ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:57.124165Z","caller":"traceutil/trace.go:171","msg":"trace[602940436] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"281.544226ms","start":"2026-02-25T17:04:56.842598Z","end":"2026-02-25T17:04:57.124142Z","steps":["trace[602940436] 'process raft request' (duration: 281.337481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:57.839619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.44083ms","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-25T17:04:57.839707Z","caller":"traceutil/trace.go:171","msg":"trace[790352508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"120.584664ms","start":"2026-02-25T17:04:57.719103Z","end":"2026-02-25T17:04:57.839688Z","steps":["trace[790352508] 'range keys from in-memory index tree' (duration: 120.224466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:57.839716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.182986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:57.839785Z","caller":"traceutil/trace.go:171","msg":"trace[59275892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"660.272528ms","start":"2026-02-25T17:04:57.179493Z","end":"2026-02-25T17:04:57.839766Z","steps":["trace[59275892] 'range keys from in-memory index tree' (duration: 660.058042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:57.839824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.179451Z","time spent":"660.36645ms","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:04:58.058929Z","caller":"traceutil/trace.go:171","msg":"trace[1107428211] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"214.841074ms","start":"2026-02-25T17:04:57.844053Z","end":"2026-02-25T17:04:58.058894Z","steps":["trace[1107428211] 'process raft request' (duration: 214.558788ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.605178Z","caller":"traceutil/trace.go:171","msg":"trace[1885143156] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"472.707697ms","start":"2026-02-25T17:05:00.132443Z","end":"2026-02-25T17:05:00.60515Z","steps":["trace[1885143156] 'process raft request' (duration: 472.580585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.605334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.132412Z","time spent":"472.84646ms","remote":"127.0.0.1:45682","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-25T17:05:00.61709Z","caller":"traceutil/trace.go:171","msg":"trace[544445506] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"438.703002ms","start":"2026-02-25T17:05:00.178364Z","end":"2026-02-25T17:05:00.617067Z","steps":["trace[544445506] 'read index received' (duration: 426.774874ms)","trace[544445506] 'applied index is now lower than readState.Index' (duration: 11.926958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:00.617215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.826355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:00.617252Z","caller":"traceutil/trace.go:171","msg":"trace[704432676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"438.919927ms","start":"2026-02-25T17:05:00.178321Z","end":"2026-02-25T17:05:00.617241Z","steps":["trace[704432676] 'agreement among raft nodes before linearized reading' (duration: 438.837125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.617204Z","caller":"traceutil/trace.go:171","msg":"trace[2029933864] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"484.174975ms","start":"2026-02-25T17:05:00.133003Z","end":"2026-02-25T17:05:00.617178Z","steps":["trace[2029933864] 'process raft request' (duration: 483.933479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.617286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.1783Z","time spent":"438.980008ms","remote":"127.0.0.1:55332","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-25T17:05:00.617406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.13299Z","time spent":"484.340489ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:02.366914Z","caller":"traceutil/trace.go:171","msg":"trace[1818418264] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"122.970856ms","start":"2026-02-25T17:05:02.243905Z","end":"2026-02-25T17:05:02.366876Z","steps":["trace[1818418264] 'process raft request' (duration: 63.227382ms)","trace[1818418264] 'compare' (duration: 59.562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:03.880605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.155576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T17:05:03.880669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270863608s","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-25T17:05:03.880742Z","caller":"traceutil/trace.go:171","msg":"trace[1599104970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"1.27094306s","start":"2026-02-25T17:05:02.609776Z","end":"2026-02-25T17:05:03.880719Z","steps":["trace[1599104970] 'range keys from in-memory index tree' (duration: 1.270715556s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:03.880807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:02.609724Z","time spent":"1.271070473s","remote":"127.0.0.1:45682","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-25T17:05:03.880746Z","caller":"traceutil/trace.go:171","msg":"trace[293274997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"736.32149ms","start":"2026-02-25T17:05:03.144379Z","end":"2026-02-25T17:05:03.880701Z","steps":["trace[293274997] 'range keys from in-memory index tree' (duration: 735.975602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:03.8808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.078556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:05:03.880964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135361689s","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-25T17:05:03.880998Z","caller":"traceutil/trace.go:171","msg":"trace[555732003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"701.291341ms","start":"2026-02-25T17:05:03.179688Z","end":"2026-02-25T17:05:03.88098Z","steps":["trace[555732003] 'range keys from in-memory index tree' (duration: 701.033945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:03.881012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260632667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T17:05:03.881006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.144361Z","time spent":"736.609466ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T17:05:03.881057Z","caller":"traceutil/trace.go:171","msg":"trace[291265356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"1.135499141s","start":"2026-02-25T17:05:02.745532Z","end":"2026-02-25T17:05:03.881032Z","steps":["trace[291265356] 'range keys from in-memory index tree' (duration: 1.135167254s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:03.881064Z","caller":"traceutil/trace.go:171","msg":"trace[265279780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"1.26071376s","start":"2026-02-25T17:05:02.620337Z","end":"2026-02-25T17:05:03.881051Z","steps":["trace[265279780] 'range keys from in-memory index tree' (duration: 1.260433293s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:03.881057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.179678Z","time spent":"701.366432ms","remote":"127.0.0.1:55340","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-25T17:05:03.881107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:02.745513Z","time spent":"1.135584894s","remote":"127.0.0.1:45682","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-25T17:05:03.88111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:02.620322Z","time spent":"1.260776921s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:03.988962Z","caller":"traceutil/trace.go:171","msg":"trace[413981580] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"104.39204ms","start":"2026-02-25T17:05:03.884542Z","end":"2026-02-25T17:05:03.988934Z","steps":["trace[413981580] 'process raft request' (duration: 104.227385ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.038231Z","caller":"traceutil/trace.go:171","msg":"trace[574225999] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"153.30766ms","start":"2026-02-25T17:05:03.884898Z","end":"2026-02-25T17:05:04.038205Z","steps":["trace[574225999] 'process raft request' (duration: 153.080805ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.038241Z","caller":"traceutil/trace.go:171","msg":"trace[617201782] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"151.99298ms","start":"2026-02-25T17:05:03.886229Z","end":"2026-02-25T17:05:04.038221Z","steps":["trace[617201782] 'process raft request' (duration: 151.943779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.038411Z","caller":"traceutil/trace.go:171","msg":"trace[697198195] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"152.420949ms","start":"2026-02-25T17:05:03.885976Z","end":"2026-02-25T17:05:04.038397Z","steps":["trace[697198195] 'process raft request' (duration: 152.128303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.442831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.135441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:05:04.442929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.710683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T17:05:04.443Z","caller":"traceutil/trace.go:171","msg":"trace[300022080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"210.841396ms","start":"2026-02-25T17:05:04.232134Z","end":"2026-02-25T17:05:04.442975Z","steps":["trace[300022080] 'range keys from in-memory index tree' (duration: 210.565649ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.442916Z","caller":"traceutil/trace.go:171","msg":"trace[524910362] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:697; }","duration":"107.257574ms","start":"2026-02-25T17:05:04.335641Z","end":"2026-02-25T17:05:04.442898Z","steps":["trace[524910362] 'count revisions from in-memory index tree' (duration: 107.07079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.682567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:05.777878Z","caller":"traceutil/trace.go:171","msg":"trace[7560105] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"987.844339ms","start":"2026-02-25T17:05:04.790006Z","end":"2026-02-25T17:05:05.77785Z","steps":["trace[7560105] 'process raft request' (duration: 987.662555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.778102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.789986Z","time spent":"988.008993ms","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.830242Z","caller":"traceutil/trace.go:171","msg":"trace[769965460] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"648.714158ms","start":"2026-02-25T17:05:05.181508Z","end":"2026-02-25T17:05:05.830222Z","steps":["trace[769965460] 'read index received' (duration: 596.304378ms)","trace[769965460] 'applied index is now lower than readState.Index' (duration: 52.4085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.830402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.876552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.830388Z","caller":"traceutil/trace.go:171","msg":"trace[258910853] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"687.287616ms","start":"2026-02-25T17:05:05.143066Z","end":"2026-02-25T17:05:05.830354Z","steps":["trace[258910853] 'process raft request' (duration: 650.642011ms)","trace[258910853] 'compare' (duration: 36.398529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.83048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.73343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:05:05.830474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.884674ms","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-25T17:05:05.830501Z","caller":"traceutil/trace.go:171","msg":"trace[1435281318] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:700; }","duration":"313.788341ms","start":"2026-02-25T17:05:05.516707Z","end":"2026-02-25T17:05:05.830495Z","steps":["trace[1435281318] 'agreement among raft nodes before linearized reading' (duration: 313.74362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.830532Z","caller":"traceutil/trace.go:171","msg":"trace[349445717] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:700; }","duration":"533.995087ms","start":"2026-02-25T17:05:05.296523Z","end":"2026-02-25T17:05:05.830518Z","steps":["trace[349445717] 'agreement among raft nodes before linearized reading' (duration: 533.880214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.830557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.516691Z","time spent":"313.857042ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:05.830565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.296503Z","time spent":"534.054808ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:05.830567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.143039Z","time spent":"687.418169ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.830428Z","caller":"traceutil/trace.go:171","msg":"trace[1783482955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"648.951233ms","start":"2026-02-25T17:05:05.181469Z","end":"2026-02-25T17:05:05.83042Z","steps":["trace[1783482955] 'agreement among raft nodes before linearized reading' (duration: 648.891132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.830695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.18145Z","time spent":"649.227589ms","remote":"127.0.0.1:55340","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-25T17:05:25.582489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:26.082908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:26.331017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.662386752s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:05:26.331481Z","caller":"traceutil/trace.go:171","msg":"trace[1603935527] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"1.24993372s","start":"2026-02-25T17:05:25.08152Z","end":"2026-02-25T17:05:26.331454Z","steps":["trace[1603935527] 'read index received' (duration: 1.249838428s)","trace[1603935527] 'applied index is now lower than readState.Index' (duration: 93.972µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:26.331597Z","caller":"traceutil/trace.go:171","msg":"trace[1630586614] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.662998245s","start":"2026-02-25T17:05:24.668512Z","end":"2026-02-25T17:05:26.331511Z","steps":["trace[1630586614] 'process raft request' (duration: 1.662824411s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.33164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250106723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:05:26.331806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:24.668493Z","time spent":"1.66320725s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:26.331943Z","caller":"traceutil/trace.go:171","msg":"trace[282977999] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:760; }","duration":"1.25040166s","start":"2026-02-25T17:05:25.08147Z","end":"2026-02-25T17:05:26.331872Z","steps":["trace[282977999] 'agreement among raft nodes before linearized reading' (duration: 1.250117854s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.332011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:25.081449Z","time spent":"1.250542213s","remote":"127.0.0.1:55404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:26.591139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.549011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:05:26.59137Z","caller":"traceutil/trace.go:171","msg":"trace[1633642229] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"259.795349ms","start":"2026-02-25T17:05:26.331552Z","end":"2026-02-25T17:05:26.591347Z","steps":["trace[1633642229] 'read index received' (duration: 27.039132ms)","trace[1633642229] 'applied index is now lower than readState.Index' (duration: 232.754986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.591734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412866166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:26.591794Z","caller":"traceutil/trace.go:171","msg":"trace[580823078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"1.413004299s","start":"2026-02-25T17:05:25.178772Z","end":"2026-02-25T17:05:26.591777Z","steps":["trace[580823078] 'agreement among raft nodes before linearized reading' (duration: 1.412880976s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.591865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:25.178739Z","time spent":"1.41309025s","remote":"127.0.0.1:55340","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-25T17:05:26.592015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.713021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T17:05:26.59212Z","caller":"traceutil/trace.go:171","msg":"trace[358811574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"261.870524ms","start":"2026-02-25T17:05:26.330232Z","end":"2026-02-25T17:05:26.592102Z","steps":["trace[358811574] 'agreement among raft nodes before linearized reading' (duration: 261.605298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:26.592331Z","caller":"traceutil/trace.go:171","msg":"trace[1145476508] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"171.801877ms","start":"2026-02-25T17:05:26.420457Z","end":"2026-02-25T17:05:26.592259Z","steps":["trace[1145476508] 'process raft request' (duration: 170.789564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.70064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.175764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:26.700726Z","caller":"traceutil/trace.go:171","msg":"trace[1230613217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"107.321927ms","start":"2026-02-25T17:05:26.593384Z","end":"2026-02-25T17:05:26.700706Z","steps":["trace[1230613217] 'range keys from in-memory index tree' (duration: 107.032381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.700906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.932252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T17:05:26.700664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.3092ms","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-25T17:05:26.700998Z","caller":"traceutil/trace.go:171","msg":"trace[2136719322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"363.025794ms","start":"2026-02-25T17:05:26.337931Z","end":"2026-02-25T17:05:26.700956Z","steps":["trace[2136719322] 'agreement among raft nodes before linearized reading' (duration: 254.277294ms)","trace[2136719322] 'range keys from in-memory index tree' (duration: 108.601907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.701063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:26.337915Z","time spent":"363.138256ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T17:05:26.700998Z","caller":"traceutil/trace.go:171","msg":"trace[134388045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"306.679729ms","start":"2026-02-25T17:05:26.394296Z","end":"2026-02-25T17:05:26.700975Z","steps":["trace[134388045] 'agreement among raft nodes before linearized reading' (duration: 197.944059ms)","trace[134388045] 'range keys from in-memory index tree' (duration: 108.28707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.701165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:26.394273Z","time spent":"306.876243ms","remote":"127.0.0.1:45682","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-25T17:05:26.701287Z","caller":"traceutil/trace.go:171","msg":"trace[1763097092] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"104.017613ms","start":"2026-02-25T17:05:26.597224Z","end":"2026-02-25T17:05:26.701241Z","steps":["trace[1763097092] 'process raft request' (duration: 21.864885ms)","trace[1763097092] 'compare' (duration: 81.869782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.701381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.843261ms","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-25T17:05:26.701499Z","caller":"traceutil/trace.go:171","msg":"trace[1545343031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"486.062957ms","start":"2026-02-25T17:05:26.215411Z","end":"2026-02-25T17:05:26.701474Z","steps":["trace[1545343031] 'agreement among raft nodes before linearized reading' (duration: 376.843536ms)","trace[1545343031] 'range keys from in-memory index tree' (duration: 108.639808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.701589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:26.215385Z","time spent":"486.18024ms","remote":"127.0.0.1:45682","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-25T17:05:29.120545Z","caller":"traceutil/trace.go:171","msg":"trace[1977947875] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"414.393066ms","start":"2026-02-25T17:05:28.706128Z","end":"2026-02-25T17:05:29.120521Z","steps":["trace[1977947875] 'read index received' (duration: 414.216332ms)","trace[1977947875] 'applied index is now lower than readState.Index' (duration: 175.944µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:29.120801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.688382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T17:05:29.120859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.598888ms","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-25T17:05:29.120887Z","caller":"traceutil/trace.go:171","msg":"trace[1490514557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"414.822355ms","start":"2026-02-25T17:05:28.706039Z","end":"2026-02-25T17:05:29.120861Z","steps":["trace[1490514557] 'agreement among raft nodes before linearized reading' (duration: 414.59397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:29.120905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.954296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T17:05:29.120932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:28.706014Z","time spent":"414.908707ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:29.120675Z","caller":"traceutil/trace.go:171","msg":"trace[749979442] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"779.514618ms","start":"2026-02-25T17:05:28.341088Z","end":"2026-02-25T17:05:29.120602Z","steps":["trace[749979442] 'process raft request' (duration: 779.312113ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:29.120968Z","caller":"traceutil/trace.go:171","msg":"trace[703794791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"349.068148ms","start":"2026-02-25T17:05:28.771885Z","end":"2026-02-25T17:05:29.120953Z","steps":["trace[703794791] 'agreement among raft nodes before linearized reading' (duration: 348.881034ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:29.120922Z","caller":"traceutil/trace.go:171","msg":"trace[897654743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"348.70558ms","start":"2026-02-25T17:05:28.772203Z","end":"2026-02-25T17:05:29.120908Z","steps":["trace[897654743] 'agreement among raft nodes before linearized reading' (duration: 348.515096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:29.12101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:28.771867Z","time spent":"349.13512ms","remote":"127.0.0.1:45682","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-25T17:05:29.121031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:28.772187Z","time spent":"348.839033ms","remote":"127.0.0.1:45682","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-25T17:05:29.121157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.802711ms","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-25T17:05:29.12124Z","caller":"traceutil/trace.go:171","msg":"trace[1851256277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"359.916724ms","start":"2026-02-25T17:05:28.761312Z","end":"2026-02-25T17:05:29.121229Z","steps":["trace[1851256277] 'agreement among raft nodes before linearized reading' (duration: 359.348201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:29.121433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:28.341067Z","time spent":"779.926996ms","remote":"127.0.0.1:45682","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-25T17:05:29.121289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:28.761292Z","time spent":"359.990525ms","remote":"127.0.0.1:45682","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-25T17:05:43.778675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964627,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:44.18925Z","caller":"traceutil/trace.go:171","msg":"trace[935141098] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"922.210825ms","start":"2026-02-25T17:05:43.267012Z","end":"2026-02-25T17:05:44.189223Z","steps":["trace[935141098] 'process raft request' (duration: 922.050601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.189414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.266979Z","time spent":"922.346238ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:44.80532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.790794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:44.805497Z","caller":"traceutil/trace.go:171","msg":"trace[1604379662] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"1.527226023s","start":"2026-02-25T17:05:43.278183Z","end":"2026-02-25T17:05:44.805409Z","steps":["trace[1604379662] 'read index received' (duration: 910.880028ms)","trace[1604379662] 'applied index is now lower than readState.Index' (duration: 616.344565ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:44.805611Z","caller":"traceutil/trace.go:171","msg":"trace[1868928697] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.534503839s","start":"2026-02-25T17:05:43.27109Z","end":"2026-02-25T17:05:44.805594Z","steps":["trace[1868928697] 'process raft request' (duration: 1.100348676s)","trace[1868928697] 'compare' (duration: 433.551089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:44.805726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.271065Z","time spent":"1.534615301s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:44.805953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434065477s","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-25T17:05:44.80604Z","caller":"traceutil/trace.go:171","msg":"trace[1406818701] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:861; }","duration":"1.43420554s","start":"2026-02-25T17:05:43.371808Z","end":"2026-02-25T17:05:44.806013Z","steps":["trace[1406818701] 'agreement among raft nodes before linearized reading' (duration: 1.434031996s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.806102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.371788Z","time spent":"1.434299321s","remote":"127.0.0.1:45838","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-25T17:05:44.806104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.4191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:44.806145Z","caller":"traceutil/trace.go:171","msg":"trace[98085794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"625.518503ms","start":"2026-02-25T17:05:44.180616Z","end":"2026-02-25T17:05:44.806134Z","steps":["trace[98085794] 'agreement among raft nodes before linearized reading' (duration: 625.441421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.806183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:44.180584Z","time spent":"625.590233ms","remote":"127.0.0.1:55340","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-25T17:05:44.806176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527960501s","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-25T17:05:44.806275Z","caller":"traceutil/trace.go:171","msg":"trace[1287929447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"1.528105634s","start":"2026-02-25T17:05:43.278142Z","end":"2026-02-25T17:05:44.806247Z","steps":["trace[1287929447] 'agreement among raft nodes before linearized reading' (duration: 1.527842808s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.80633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.278122Z","time spent":"1.528195257s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:45.060787Z","caller":"traceutil/trace.go:171","msg":"trace[524127457] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"250.615861ms","start":"2026-02-25T17:05:44.810145Z","end":"2026-02-25T17:05:45.060761Z","steps":["trace[524127457] 'process raft request' (duration: 216.243883ms)","trace[524127457] 'compare' (duration: 34.263915ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:55.385409Z","caller":"traceutil/trace.go:171","msg":"trace[1414014310] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"227.6179ms","start":"2026-02-25T17:05:55.157773Z","end":"2026-02-25T17:05:55.38539Z","steps":["trace[1414014310] 'process raft request' (duration: 227.182971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:56.833998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.280645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:05:57.066134Z","caller":"traceutil/trace.go:171","msg":"trace[1731877665] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"127.000193ms","start":"2026-02-25T17:05:56.939104Z","end":"2026-02-25T17:05:57.066104Z","steps":["trace[1731877665] 'process raft request' (duration: 126.828019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.265053Z","caller":"traceutil/trace.go:171","msg":"trace[1015819047] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"162.830304ms","start":"2026-02-25T17:05:57.102207Z","end":"2026-02-25T17:05:57.265037Z","steps":["trace[1015819047] 'process raft request' (duration: 65.616715ms)","trace[1015819047] 'compare' (duration: 96.787449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:57.661622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.272313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T17:05:57.661669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.667792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:05:57.661744Z","caller":"traceutil/trace.go:171","msg":"trace[2068832757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"273.442707ms","start":"2026-02-25T17:05:57.388272Z","end":"2026-02-25T17:05:57.661714Z","steps":["trace[2068832757] 'range keys from in-memory index tree' (duration: 273.10171ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.661759Z","caller":"traceutil/trace.go:171","msg":"trace[1673029213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"258.797135ms","start":"2026-02-25T17:05:57.402941Z","end":"2026-02-25T17:05:57.661738Z","steps":["trace[1673029213] 'range keys from in-memory index tree' (duration: 258.514289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.661644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.29452ms","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-25T17:05:57.662262Z","caller":"traceutil/trace.go:171","msg":"trace[75662404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"249.603697ms","start":"2026-02-25T17:05:57.412324Z","end":"2026-02-25T17:05:57.661928Z","steps":["trace[75662404] 'range keys from in-memory index tree' (duration: 249.173808ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.774919Z","caller":"traceutil/trace.go:171","msg":"trace[147151484] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"107.746158ms","start":"2026-02-25T17:05:57.667152Z","end":"2026-02-25T17:05:57.774898Z","steps":["trace[147151484] 'process raft request' (duration: 107.705367ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.77504Z","caller":"traceutil/trace.go:171","msg":"trace[942587930] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"108.195218ms","start":"2026-02-25T17:05:57.666789Z","end":"2026-02-25T17:05:57.774984Z","steps":["trace[942587930] 'process raft request' (duration: 102.495829ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:00.035632Z","caller":"traceutil/trace.go:171","msg":"trace[797930642] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"224.938019ms","start":"2026-02-25T17:05:59.810677Z","end":"2026-02-25T17:06:00.035615Z","steps":["trace[797930642] 'process raft request' (duration: 224.832347ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:00.059087Z","caller":"traceutil/trace.go:171","msg":"trace[665801808] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"248.145275ms","start":"2026-02-25T17:05:59.810905Z","end":"2026-02-25T17:06:00.05905Z","steps":["trace[665801808] 'process raft request' (duration: 247.854479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.266555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.194135ms","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-25T17:06:01.266654Z","caller":"traceutil/trace.go:171","msg":"trace[328756029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"956.339048ms","start":"2026-02-25T17:06:00.310293Z","end":"2026-02-25T17:06:01.266632Z","steps":["trace[328756029] 'range keys from in-memory index tree' (duration: 956.020421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.266701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:00.310279Z","time spent":"956.412729ms","remote":"127.0.0.1:45682","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-25T17:06:01.266767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.858526ms","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-25T17:06:01.266843Z","caller":"traceutil/trace.go:171","msg":"trace[1374200221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"189.975418ms","start":"2026-02-25T17:06:01.076848Z","end":"2026-02-25T17:06:01.266824Z","steps":["trace[1374200221] 'range keys from in-memory index tree' (duration: 189.622141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.266946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088455237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:01.267042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.773766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:01.267081Z","caller":"traceutil/trace.go:171","msg":"trace[2037660920] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:903; }","duration":"784.849918ms","start":"2026-02-25T17:06:00.482219Z","end":"2026-02-25T17:06:01.267069Z","steps":["trace[2037660920] 'count revisions from in-memory index tree' (duration: 784.696835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.267113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:00.482198Z","time spent":"784.906648ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:01.26703Z","caller":"traceutil/trace.go:171","msg":"trace[535770838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"1.08858066s","start":"2026-02-25T17:06:00.178429Z","end":"2026-02-25T17:06:01.267009Z","steps":["trace[535770838] 'range keys from in-memory index tree' (duration: 1.088379586s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.267333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:00.17841Z","time spent":"1.088890507s","remote":"127.0.0.1:55332","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-25T17:06:01.776335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:06:01.877322Z","caller":"traceutil/trace.go:171","msg":"trace[450362813] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"606.247837ms","start":"2026-02-25T17:06:01.271052Z","end":"2026-02-25T17:06:01.8773Z","steps":["trace[450362813] 'process raft request' (duration: 606.096404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.877503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:01.271032Z","time spent":"606.359789ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:01.944623Z","caller":"traceutil/trace.go:171","msg":"trace[1455739039] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:943; }","duration":"668.356462ms","start":"2026-02-25T17:06:01.276241Z","end":"2026-02-25T17:06:01.944597Z","steps":["trace[1455739039] 'read index received' (duration: 600.831004ms)","trace[1455739039] 'applied index is now lower than readState.Index' (duration: 67.524628ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:01.94475Z","caller":"traceutil/trace.go:171","msg":"trace[1331503484] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"673.55182ms","start":"2026-02-25T17:06:01.27117Z","end":"2026-02-25T17:06:01.944722Z","steps":["trace[1331503484] 'process raft request' (duration: 673.311325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.944883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:01.271142Z","time spent":"673.672433ms","remote":"127.0.0.1:45682","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-25T17:06:01.944896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.843757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T17:06:01.944771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.517766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:01.944917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.240739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.137\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:06:01.94494Z","caller":"traceutil/trace.go:171","msg":"trace[1133198221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"132.914758ms","start":"2026-02-25T17:06:01.812012Z","end":"2026-02-25T17:06:01.944927Z","steps":["trace[1133198221] 'agreement among raft nodes before linearized reading' (duration: 132.793646ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.944981Z","caller":"traceutil/trace.go:171","msg":"trace[1986414523] range","detail":"{range_begin:/registry/masterleases/199.19.213.137; range_end:; response_count:1; response_revision:905; }","duration":"664.30668ms","start":"2026-02-25T17:06:01.280659Z","end":"2026-02-25T17:06:01.944966Z","steps":["trace[1986414523] 'agreement among raft nodes before linearized reading' (duration: 664.127616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.944989Z","caller":"traceutil/trace.go:171","msg":"trace[1436261544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"668.718751ms","start":"2026-02-25T17:06:01.276225Z","end":"2026-02-25T17:06:01.944944Z","steps":["trace[1436261544] 'agreement among raft nodes before linearized reading' (duration: 668.494956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.945036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:01.280608Z","time spent":"664.415963ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.137\" "} {"level":"warn","ts":"2026-02-25T17:06:01.945042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:01.276157Z","time spent":"668.871844ms","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:02.097283Z","caller":"traceutil/trace.go:171","msg":"trace[59071845] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"148.458118ms","start":"2026-02-25T17:06:01.948806Z","end":"2026-02-25T17:06:02.097264Z","steps":["trace[59071845] 'process raft request' (duration: 147.747013ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:02.390867Z","caller":"traceutil/trace.go:171","msg":"trace[944535333] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:948; }","duration":"228.664634ms","start":"2026-02-25T17:06:02.162177Z","end":"2026-02-25T17:06:02.390842Z","steps":["trace[944535333] 'read index received' (duration: 228.525821ms)","trace[944535333] 'applied index is now lower than readState.Index' (duration: 137.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:02.390959Z","caller":"traceutil/trace.go:171","msg":"trace[698557663] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"289.369957ms","start":"2026-02-25T17:06:02.101544Z","end":"2026-02-25T17:06:02.390914Z","steps":["trace[698557663] 'process raft request' (duration: 289.248814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:02.391037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.848248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T17:06:02.391083Z","caller":"traceutil/trace.go:171","msg":"trace[437970083] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:909; }","duration":"228.93597ms","start":"2026-02-25T17:06:02.162133Z","end":"2026-02-25T17:06:02.391069Z","steps":["trace[437970083] 'agreement among raft nodes before linearized reading' (duration: 228.812997ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:02.391334Z","caller":"traceutil/trace.go:171","msg":"trace[271235311] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"289.828377ms","start":"2026-02-25T17:06:02.101488Z","end":"2026-02-25T17:06:02.391316Z","steps":["trace[271235311] 'process raft request' (duration: 289.155412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:02.620873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.66253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:02.621043Z","caller":"traceutil/trace.go:171","msg":"trace[2061364008] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"228.213913ms","start":"2026-02-25T17:06:02.392792Z","end":"2026-02-25T17:06:02.621006Z","steps":["trace[2061364008] 'read index received' (duration: 73.342599ms)","trace[2061364008] 'applied index is now lower than readState.Index' (duration: 154.869654ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:02.621054Z","caller":"traceutil/trace.go:171","msg":"trace[345184219] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"249.607357ms","start":"2026-02-25T17:06:02.371417Z","end":"2026-02-25T17:06:02.621025Z","steps":["trace[345184219] 'process raft request' (duration: 94.673032ms)","trace[345184219] 'compare' (duration: 154.512196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:02.621184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.98163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:02.621243Z","caller":"traceutil/trace.go:171","msg":"trace[1079701775] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:910; }","duration":"202.036711ms","start":"2026-02-25T17:06:02.419184Z","end":"2026-02-25T17:06:02.621221Z","steps":["trace[1079701775] 'agreement among raft nodes before linearized reading' (duration: 201.95607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:02.621251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.448059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:06:02.621327Z","caller":"traceutil/trace.go:171","msg":"trace[980890219] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:910; }","duration":"228.55695ms","start":"2026-02-25T17:06:02.392754Z","end":"2026-02-25T17:06:02.621311Z","steps":["trace[980890219] 'agreement among raft nodes before linearized reading' (duration: 228.371056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:03.697757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.133082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:03.697835Z","caller":"traceutil/trace.go:171","msg":"trace[263119318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"518.259155ms","start":"2026-02-25T17:06:03.179561Z","end":"2026-02-25T17:06:03.69782Z","steps":["trace[263119318] 'range keys from in-memory index tree' (duration: 518.056471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:03.697863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:03.179542Z","time spent":"518.313547ms","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:04.164014Z","caller":"traceutil/trace.go:171","msg":"trace[18909501] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"215.20099ms","start":"2026-02-25T17:06:03.948793Z","end":"2026-02-25T17:06:04.163993Z","steps":["trace[18909501] 'read index received' (duration: 215.063237ms)","trace[18909501] 'applied index is now lower than readState.Index' (duration: 136.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:04.164146Z","caller":"traceutil/trace.go:171","msg":"trace[1843295888] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"278.348008ms","start":"2026-02-25T17:06:03.885785Z","end":"2026-02-25T17:06:04.164133Z","steps":["trace[1843295888] 'process raft request' (duration: 278.084752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:04.16435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.548207ms","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-25T17:06:04.164391Z","caller":"traceutil/trace.go:171","msg":"trace[710476895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"215.645099ms","start":"2026-02-25T17:06:03.948736Z","end":"2026-02-25T17:06:04.164381Z","steps":["trace[710476895] 'agreement among raft nodes before linearized reading' (duration: 215.502826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:04.895088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:05.395258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:05.896272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:06.396437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142964780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:06.402975Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.229563114s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T17:06:07.181906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000152644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T17:06:07.181971Z","caller":"traceutil/trace.go:171","msg":"trace[618076481] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000273446s","start":"2026-02-25T17:06:05.181681Z","end":"2026-02-25T17:06:07.181954Z","steps":["trace[618076481] 'agreement among raft nodes before linearized reading' (duration: 2.000194985s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:07.182011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.18165Z","time spent":"2.000348628s","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 17:06:07 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T17:06:07.499217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095957857s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:07.50012Z","caller":"traceutil/trace.go:171","msg":"trace[1223131757] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"3.331468006s","start":"2026-02-25T17:06:04.168629Z","end":"2026-02-25T17:06:07.500097Z","steps":["trace[1223131757] 'process raft request' (duration: 3.330666268s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:07.50029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:04.168621Z","time spent":"3.331575768s","remote":"127.0.0.1:45682","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-25T17:06:07.500802Z","caller":"traceutil/trace.go:171","msg":"trace[1639852324] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"3.332086781s","start":"2026-02-25T17:06:04.16791Z","end":"2026-02-25T17:06:07.499997Z","steps":["trace[1639852324] 'process raft request' (duration: 2.235266064s)","trace[1639852324] 'compare' (duration: 1.09565828s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:07.500913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:04.167883Z","time spent":"3.33297409s","remote":"127.0.0.1:45682","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-25T17:06:08.610936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111582769s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:06:08.611037Z","caller":"traceutil/trace.go:171","msg":"trace[1309459376] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:953; }","duration":"4.21609321s","start":"2026-02-25T17:06:04.394925Z","end":"2026-02-25T17:06:08.611018Z","steps":["trace[1309459376] 'read index received' (duration: 2.008233256s)","trace[1309459376] 'applied index is now lower than readState.Index' (duration: 2.207858354s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:08.611287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.216358878s","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-25T17:06:08.611337Z","caller":"traceutil/trace.go:171","msg":"trace[1905930463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"4.216413399s","start":"2026-02-25T17:06:04.394907Z","end":"2026-02-25T17:06:08.611321Z","steps":["trace[1905930463] 'agreement among raft nodes before linearized reading' (duration: 4.216201264s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.611389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:04.394838Z","time spent":"4.216538481s","remote":"127.0.0.1:45682","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-25T17:06:08.76115Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.735937256s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:06:08.761558Z","caller":"traceutil/trace.go:171","msg":"trace[170877205] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"2.278659895s","start":"2026-02-25T17:06:06.482875Z","end":"2026-02-25T17:06:08.761535Z","steps":["trace[170877205] 'process raft request' (duration: 2.278497401s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.76179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.482851Z","time spent":"2.27884683s","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4867,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:08.981434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.274288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:08.981635Z","caller":"traceutil/trace.go:171","msg":"trace[2036479326] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:956; }","duration":"370.558764ms","start":"2026-02-25T17:06:08.611063Z","end":"2026-02-25T17:06:08.981621Z","steps":["trace[2036479326] 'read index received' (duration: 150.379762ms)","trace[2036479326] 'applied index is now lower than readState.Index' (duration: 220.178322ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:08.981723Z","caller":"traceutil/trace.go:171","msg":"trace[1014884705] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.317858347s","start":"2026-02-25T17:06:07.663857Z","end":"2026-02-25T17:06:08.981715Z","steps":["trace[1014884705] 'process raft request' (duration: 1.183239031s)","trace[1014884705] 'compare' (duration: 134.165516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:08.981804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.663837Z","time spent":"1.317933139s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:08.981947Z","caller":"traceutil/trace.go:171","msg":"trace[1962881789] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"366.123264ms","start":"2026-02-25T17:06:08.615816Z","end":"2026-02-25T17:06:08.981939Z","steps":["trace[1962881789] 'process raft request' (duration: 365.759296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.61578Z","time spent":"366.208346ms","remote":"127.0.0.1:45682","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-25T17:06:08.982099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.057115813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:08.982177Z","caller":"traceutil/trace.go:171","msg":"trace[1444376251] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:917; }","duration":"2.057237005s","start":"2026-02-25T17:06:06.924923Z","end":"2026-02-25T17:06:08.98216Z","steps":["trace[1444376251] 'agreement among raft nodes before linearized reading' (duration: 2.05703654s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.799609826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.98217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.751178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.982317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984022539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:08.982348Z","caller":"traceutil/trace.go:171","msg":"trace[1740780007] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:917; }","duration":"1.984097171s","start":"2026-02-25T17:06:06.998242Z","end":"2026-02-25T17:06:08.98234Z","steps":["trace[1740780007] 'agreement among raft nodes before linearized reading' (duration: 1.9840555s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.99822Z","time spent":"1.984147261s","remote":"127.0.0.1:55448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:08.982327Z","caller":"traceutil/trace.go:171","msg":"trace[224968766] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:917; }","duration":"389.956343ms","start":"2026-02-25T17:06:08.592349Z","end":"2026-02-25T17:06:08.982306Z","steps":["trace[224968766] 'agreement among raft nodes before linearized reading' (duration: 389.696667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.965631779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.98238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194336162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.982443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.077478688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:08.982506Z","caller":"traceutil/trace.go:171","msg":"trace[903950541] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:917; }","duration":"3.07759535s","start":"2026-02-25T17:06:05.904901Z","end":"2026-02-25T17:06:08.982497Z","steps":["trace[903950541] 'agreement among raft nodes before linearized reading' (duration: 3.077503718s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.90488Z","time spent":"3.077659002s","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:08.982556Z","caller":"traceutil/trace.go:171","msg":"trace[1391699720] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:917; }","duration":"1.194492655s","start":"2026-02-25T17:06:07.78796Z","end":"2026-02-25T17:06:08.982453Z","steps":["trace[1391699720] 'agreement among raft nodes before linearized reading' (duration: 1.19426882s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.924904Z","time spent":"2.057308537s","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":87,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:08.982433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.592331Z","time spent":"390.079715ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:08.982283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87578581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.982777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.58379573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T17:06:08.982817Z","caller":"traceutil/trace.go:171","msg":"trace[1574539865] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:917; }","duration":"1.876346643s","start":"2026-02-25T17:06:07.106461Z","end":"2026-02-25T17:06:08.982808Z","steps":["trace[1574539865] 'agreement among raft nodes before linearized reading' (duration: 1.875797601s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.98285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.106446Z","time spent":"1.876395424s","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:08.982872Z","caller":"traceutil/trace.go:171","msg":"trace[1413406747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"4.583883971s","start":"2026-02-25T17:06:04.398937Z","end":"2026-02-25T17:06:08.982821Z","steps":["trace[1413406747] 'agreement among raft nodes before linearized reading' (duration: 4.583602565s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.982987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:04.398875Z","time spent":"4.584065305s","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:06:08.982452Z","caller":"traceutil/trace.go:171","msg":"trace[315998398] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:917; }","duration":"2.965792502s","start":"2026-02-25T17:06:06.016641Z","end":"2026-02-25T17:06:08.982433Z","steps":["trace[315998398] 'agreement among raft nodes before linearized reading' (duration: 2.965610598s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.983088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.016627Z","time spent":"2.966437807s","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:08.982143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.814483248s","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-25T17:06:08.983217Z","caller":"traceutil/trace.go:171","msg":"trace[592815715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"2.815584743s","start":"2026-02-25T17:06:06.167624Z","end":"2026-02-25T17:06:08.983208Z","steps":["trace[592815715] 'agreement among raft nodes before linearized reading' (duration: 2.814451958s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.983244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.167603Z","time spent":"2.815634325s","remote":"127.0.0.1:45682","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-25T17:06:08.983426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.607453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:08.98348Z","caller":"traceutil/trace.go:171","msg":"trace[363637081] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:917; }","duration":"335.719755ms","start":"2026-02-25T17:06:08.647751Z","end":"2026-02-25T17:06:08.98347Z","steps":["trace[363637081] 'agreement among raft nodes before linearized reading' (duration: 334.769854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.98351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.647717Z","time spent":"335.782227ms","remote":"127.0.0.1:45760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:08.983519Z","caller":"traceutil/trace.go:171","msg":"trace[1111315100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"1.80113496s","start":"2026-02-25T17:06:07.182369Z","end":"2026-02-25T17:06:08.983504Z","steps":["trace[1111315100] 'agreement among raft nodes before linearized reading' (duration: 1.799652647s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.983654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.182347Z","time spent":"1.801295975s","remote":"127.0.0.1:55332","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-25T17:06:08.983724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.787941Z","time spent":"1.195772824s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:09.619516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.830182ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:09.619607Z","caller":"traceutil/trace.go:171","msg":"trace[581934872] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:917; }","duration":"363.934454ms","start":"2026-02-25T17:06:09.25566Z","end":"2026-02-25T17:06:09.619594Z","steps":["trace[581934872] 'range keys from in-memory index tree' (duration: 363.78929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:09.620248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.994755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:09.620447Z","caller":"traceutil/trace.go:171","msg":"trace[1098138488] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"632.126203ms","start":"2026-02-25T17:06:08.988295Z","end":"2026-02-25T17:06:09.620421Z","steps":["trace[1098138488] 'process raft request' (duration: 233.862612ms)","trace[1098138488] 'compare' (duration: 397.646687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:09.620602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.988278Z","time spent":"632.272046ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:09.718812Z","caller":"traceutil/trace.go:171","msg":"trace[818615018] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:959; }","duration":"728.165965ms","start":"2026-02-25T17:06:08.990623Z","end":"2026-02-25T17:06:09.718789Z","steps":["trace[818615018] 'read index received' (duration: 231.56682ms)","trace[818615018] 'applied index is now lower than readState.Index' (duration: 496.598255ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:09.718894Z","caller":"traceutil/trace.go:171","msg":"trace[893585324] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"730.539849ms","start":"2026-02-25T17:06:08.988314Z","end":"2026-02-25T17:06:09.718854Z","steps":["trace[893585324] 'process raft request' (duration: 730.302243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:09.719068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.425031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:09.718978Z","caller":"traceutil/trace.go:171","msg":"trace[1606063544] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"730.58042ms","start":"2026-02-25T17:06:08.98838Z","end":"2026-02-25T17:06:09.71896Z","steps":["trace[1606063544] 'process raft request' (duration: 730.350925ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:09.719123Z","caller":"traceutil/trace.go:171","msg":"trace[1452336816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"728.513432ms","start":"2026-02-25T17:06:08.990597Z","end":"2026-02-25T17:06:09.71911Z","steps":["trace[1452336816] 'agreement among raft nodes before linearized reading' (duration: 728.4096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:09.719169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.990588Z","time spent":"728.569683ms","remote":"127.0.0.1:55340","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-25T17:06:09.71926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.075853ms","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-25T17:06:09.719305Z","caller":"traceutil/trace.go:171","msg":"trace[1804860166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"210.132155ms","start":"2026-02-25T17:06:09.509164Z","end":"2026-02-25T17:06:09.719296Z","steps":["trace[1804860166] 'agreement among raft nodes before linearized reading' (duration: 209.9157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:09.719344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.130944ms","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-25T17:06:09.719467Z","caller":"traceutil/trace.go:171","msg":"trace[1350066727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"210.305868ms","start":"2026-02-25T17:06:09.509146Z","end":"2026-02-25T17:06:09.719452Z","steps":["trace[1350066727] 'agreement among raft nodes before linearized reading' (duration: 209.916239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:09.719343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.988359Z","time spent":"730.778594ms","remote":"127.0.0.1:45682","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-25T17:06:09.719726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.988291Z","time spent":"730.801095ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:10.121813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:09.742764Z","time spent":"379.042416ms","remote":"127.0.0.1:36508","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T17:06:10.121831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.300469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:10.122007Z","caller":"traceutil/trace.go:171","msg":"trace[135872799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"401.540365ms","start":"2026-02-25T17:06:09.72044Z","end":"2026-02-25T17:06:10.121981Z","steps":["trace[135872799] 'range keys from in-memory index tree' (duration: 401.199987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:10.12213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:09.720423Z","time spent":"401.690548ms","remote":"127.0.0.1:55332","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-25T17:06:10.122139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.085686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:10.12235Z","caller":"traceutil/trace.go:171","msg":"trace[1965854899] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"131.23933ms","start":"2026-02-25T17:06:09.991086Z","end":"2026-02-25T17:06:10.122326Z","steps":["trace[1965854899] 'read index received' (duration: 42.772437ms)","trace[1965854899] 'applied index is now lower than readState.Index' (duration: 88.465483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:10.12245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.364362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:10.122491Z","caller":"traceutil/trace.go:171","msg":"trace[502099268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"131.441454ms","start":"2026-02-25T17:06:09.991035Z","end":"2026-02-25T17:06:10.122476Z","steps":["trace[502099268] 'agreement among raft nodes before linearized reading' (duration: 131.371662ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:10.122584Z","caller":"traceutil/trace.go:171","msg":"trace[2102962742] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"399.232523ms","start":"2026-02-25T17:06:09.723313Z","end":"2026-02-25T17:06:10.122546Z","steps":["trace[2102962742] 'process raft request' (duration: 248.649295ms)","trace[2102962742] 'compare' (duration: 149.985954ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:10.122627Z","caller":"traceutil/trace.go:171","msg":"trace[2016331164] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"397.998025ms","start":"2026-02-25T17:06:09.724611Z","end":"2026-02-25T17:06:10.122609Z","steps":["trace[2016331164] 'process raft request' (duration: 397.641317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:10.122701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:09.723298Z","time spent":"399.337955ms","remote":"127.0.0.1:45682","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-25T17:06:10.122725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:09.724603Z","time spent":"398.052336ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:15.207301Z","caller":"traceutil/trace.go:171","msg":"trace[1470578737] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"194.089131ms","start":"2026-02-25T17:06:15.013181Z","end":"2026-02-25T17:06:15.20727Z","steps":["trace[1470578737] 'process raft request' (duration: 193.836615ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:17.955569Z","caller":"traceutil/trace.go:171","msg":"trace[1929958232] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"196.636589ms","start":"2026-02-25T17:06:17.75891Z","end":"2026-02-25T17:06:17.955547Z","steps":["trace[1929958232] 'read index received' (duration: 196.399564ms)","trace[1929958232] 'applied index is now lower than readState.Index' (duration: 236.235µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:17.95584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.911015ms","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-25T17:06:17.955935Z","caller":"traceutil/trace.go:171","msg":"trace[1914136374] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"253.669349ms","start":"2026-02-25T17:06:17.702035Z","end":"2026-02-25T17:06:17.955704Z","steps":["trace[1914136374] 'process raft request' (duration: 253.376582ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:17.956075Z","caller":"traceutil/trace.go:171","msg":"trace[1808377807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"197.191591ms","start":"2026-02-25T17:06:17.758859Z","end":"2026-02-25T17:06:17.956051Z","steps":["trace[1808377807] 'agreement among raft nodes before linearized reading' (duration: 196.786032ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.368111Z","caller":"traceutil/trace.go:171","msg":"trace[156038121] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"154.017935ms","start":"2026-02-25T17:06:20.214036Z","end":"2026-02-25T17:06:20.368054Z","steps":["trace[156038121] 'process raft request' (duration: 153.845581ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:22.283399Z","caller":"traceutil/trace.go:171","msg":"trace[121971612] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"164.536473ms","start":"2026-02-25T17:06:22.11883Z","end":"2026-02-25T17:06:22.283366Z","steps":["trace[121971612] 'process raft request' (duration: 164.279847ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:23.301105Z","caller":"traceutil/trace.go:171","msg":"trace[1788925899] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"120.914276ms","start":"2026-02-25T17:06:23.18017Z","end":"2026-02-25T17:06:23.301084Z","steps":["trace[1788925899] 'read index received' (duration: 120.729221ms)","trace[1788925899] 'applied index is now lower than readState.Index' (duration: 184.135µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:23.301254Z","caller":"traceutil/trace.go:171","msg":"trace[762456504] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"208.240371ms","start":"2026-02-25T17:06:23.092996Z","end":"2026-02-25T17:06:23.301236Z","steps":["trace[762456504] 'process raft request' (duration: 207.968795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:23.301325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.14382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:23.301367Z","caller":"traceutil/trace.go:171","msg":"trace[458736995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"121.227173ms","start":"2026-02-25T17:06:23.180128Z","end":"2026-02-25T17:06:23.301355Z","steps":["trace[458736995] 'agreement among raft nodes before linearized reading' (duration: 121.143261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:24.058958Z","caller":"traceutil/trace.go:171","msg":"trace[1877517527] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"175.806926ms","start":"2026-02-25T17:06:23.883128Z","end":"2026-02-25T17:06:24.058935Z","steps":["trace[1877517527] 'process raft request' (duration: 147.382216ms)","trace[1877517527] 'compare' (duration: 28.202625ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:24.276153Z","caller":"traceutil/trace.go:171","msg":"trace[545599929] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"213.221777ms","start":"2026-02-25T17:06:24.062896Z","end":"2026-02-25T17:06:24.276118Z","steps":["trace[545599929] 'process raft request' (duration: 157.922422ms)","trace[545599929] 'compare' (duration: 54.994698ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:24.276349Z","caller":"traceutil/trace.go:171","msg":"trace[1234817585] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"210.980806ms","start":"2026-02-25T17:06:24.065313Z","end":"2026-02-25T17:06:24.276293Z","steps":["trace[1234817585] 'process raft request' (duration: 210.865012ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:24.27642Z","caller":"traceutil/trace.go:171","msg":"trace[1948368756] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"213.289949ms","start":"2026-02-25T17:06:24.06312Z","end":"2026-02-25T17:06:24.27641Z","steps":["trace[1948368756] 'process raft request' (duration: 212.91542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:24.446156Z","caller":"traceutil/trace.go:171","msg":"trace[1591299414] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"155.487284ms","start":"2026-02-25T17:06:24.29064Z","end":"2026-02-25T17:06:24.446128Z","steps":["trace[1591299414] 'process raft request' (duration: 123.041751ms)","trace[1591299414] 'compare' (duration: 32.179937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:24.446319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.904504ms","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-25T17:06:24.446367Z","caller":"traceutil/trace.go:171","msg":"trace[1367485515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"115.975055ms","start":"2026-02-25T17:06:24.330375Z","end":"2026-02-25T17:06:24.44635Z","steps":["trace[1367485515] 'agreement among raft nodes before linearized reading' (duration: 115.820942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:24.446082Z","caller":"traceutil/trace.go:171","msg":"trace[1968183264] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"115.662009ms","start":"2026-02-25T17:06:24.330396Z","end":"2026-02-25T17:06:24.446058Z","steps":["trace[1968183264] 'read index received' (duration: 83.295447ms)","trace[1968183264] 'applied index is now lower than readState.Index' (duration: 32.365512ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:26.573726Z","caller":"traceutil/trace.go:171","msg":"trace[1130276038] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"113.575401ms","start":"2026-02-25T17:06:26.460129Z","end":"2026-02-25T17:06:26.573705Z","steps":["trace[1130276038] 'read index received' (duration: 113.388617ms)","trace[1130276038] 'applied index is now lower than readState.Index' (duration: 185.584µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:26.573863Z","caller":"traceutil/trace.go:171","msg":"trace[1705795915] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"119.12076ms","start":"2026-02-25T17:06:26.454701Z","end":"2026-02-25T17:06:26.573822Z","steps":["trace[1705795915] 'process raft request' (duration: 118.882115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:26.573934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.784476ms","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-25T17:06:26.573966Z","caller":"traceutil/trace.go:171","msg":"trace[901815988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1008; }","duration":"113.896648ms","start":"2026-02-25T17:06:26.460061Z","end":"2026-02-25T17:06:26.573958Z","steps":["trace[901815988] 'agreement among raft nodes before linearized reading' (duration: 113.771365ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:26.794414Z","caller":"traceutil/trace.go:171","msg":"trace[421826241] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"214.467326ms","start":"2026-02-25T17:06:26.579911Z","end":"2026-02-25T17:06:26.794378Z","steps":["trace[421826241] 'process raft request' (duration: 214.260281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:26.794554Z","caller":"traceutil/trace.go:171","msg":"trace[878465659] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"214.593509ms","start":"2026-02-25T17:06:26.579912Z","end":"2026-02-25T17:06:26.794505Z","steps":["trace[878465659] 'process raft request' (duration: 214.385244ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:32.36953Z","caller":"traceutil/trace.go:171","msg":"trace[2116440817] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"244.550306ms","start":"2026-02-25T17:06:32.124949Z","end":"2026-02-25T17:06:32.369499Z","steps":["trace[2116440817] 'process raft request' (duration: 244.247988ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:32.36953Z","caller":"traceutil/trace.go:171","msg":"trace[211931764] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"189.840082ms","start":"2026-02-25T17:06:32.179584Z","end":"2026-02-25T17:06:32.369424Z","steps":["trace[211931764] 'read index received' (duration: 189.514605ms)","trace[211931764] 'applied index is now lower than readState.Index' (duration: 324.547µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:32.369594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.031048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:32.369673Z","caller":"traceutil/trace.go:171","msg":"trace[281686877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"190.164721ms","start":"2026-02-25T17:06:32.179492Z","end":"2026-02-25T17:06:32.369657Z","steps":["trace[281686877] 'agreement among raft nodes before linearized reading' (duration: 190.046088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.593855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.986791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:33.593942Z","caller":"traceutil/trace.go:171","msg":"trace[1100466804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"413.167176ms","start":"2026-02-25T17:06:33.180752Z","end":"2026-02-25T17:06:33.593919Z","steps":["trace[1100466804] 'range keys from in-memory index tree' (duration: 412.90977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.594208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.180734Z","time spent":"413.238757ms","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:33.625135Z","caller":"traceutil/trace.go:171","msg":"trace[840040307] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"125.668121ms","start":"2026-02-25T17:06:33.499437Z","end":"2026-02-25T17:06:33.625105Z","steps":["trace[840040307] 'read index received' (duration: 125.489157ms)","trace[840040307] 'applied index is now lower than readState.Index' (duration: 177.714µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:33.625283Z","caller":"traceutil/trace.go:171","msg":"trace[58340912] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"204.319979ms","start":"2026-02-25T17:06:33.420923Z","end":"2026-02-25T17:06:33.625243Z","steps":["trace[58340912] 'process raft request' (duration: 203.974222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.625433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.974039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T17:06:33.625495Z","caller":"traceutil/trace.go:171","msg":"trace[1661352954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"126.044751ms","start":"2026-02-25T17:06:33.499432Z","end":"2026-02-25T17:06:33.625476Z","steps":["trace[1661352954] 'agreement among raft nodes before linearized reading' (duration: 125.815126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:39.489081Z","caller":"traceutil/trace.go:171","msg":"trace[1194919596] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"119.14186ms","start":"2026-02-25T17:06:39.36991Z","end":"2026-02-25T17:06:39.489052Z","steps":["trace[1194919596] 'process raft request' (duration: 118.919725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.098948Z","caller":"traceutil/trace.go:171","msg":"trace[735593206] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"130.290729ms","start":"2026-02-25T17:06:40.968631Z","end":"2026-02-25T17:06:41.098921Z","steps":["trace[735593206] 'process raft request' (duration: 130.107915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.223884Z","caller":"traceutil/trace.go:171","msg":"trace[1794271505] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"119.417716ms","start":"2026-02-25T17:06:41.104427Z","end":"2026-02-25T17:06:41.223845Z","steps":["trace[1794271505] 'process raft request' (duration: 71.453741ms)","trace[1794271505] 'compare' (duration: 47.725069ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:41.835591Z","caller":"traceutil/trace.go:171","msg":"trace[297115563] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"109.785202ms","start":"2026-02-25T17:06:41.725781Z","end":"2026-02-25T17:06:41.835566Z","steps":["trace[297115563] 'process raft request' (duration: 104.909259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.680425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679533666142967041,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:09:33.813396Z","caller":"traceutil/trace.go:171","msg":"trace[1329162414] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"661.885493ms","start":"2026-02-25T17:09:33.151488Z","end":"2026-02-25T17:09:33.813374Z","steps":["trace[1329162414] 'process raft request' (duration: 661.76589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.813502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:33.15147Z","time spent":"661.979565ms","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:09:33.824971Z","caller":"traceutil/trace.go:171","msg":"trace[1964664285] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"644.995456ms","start":"2026-02-25T17:09:33.179953Z","end":"2026-02-25T17:09:33.824948Z","steps":["trace[1964664285] 'read index received' (duration: 633.40968ms)","trace[1964664285] 'applied index is now lower than readState.Index' (duration: 11.585006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:09:33.825429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.206956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:09:33.825469Z","caller":"traceutil/trace.go:171","msg":"trace[1711837302] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1818; }","duration":"620.298558ms","start":"2026-02-25T17:09:33.20516Z","end":"2026-02-25T17:09:33.825459Z","steps":["trace[1711837302] 'agreement among raft nodes before linearized reading' (duration: 620.215276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.825499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:33.20514Z","time spent":"620.35053ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:09:33.825724Z","caller":"traceutil/trace.go:171","msg":"trace[1126066156] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"439.062669ms","start":"2026-02-25T17:09:33.386652Z","end":"2026-02-25T17:09:33.825715Z","steps":["trace[1126066156] 'process raft request' (duration: 438.196728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.825788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:33.386626Z","time spent":"439.12198ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:09:33.825867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.924097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:09:33.825892Z","caller":"traceutil/trace.go:171","msg":"trace[1195625748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1818; }","duration":"645.948458ms","start":"2026-02-25T17:09:33.179938Z","end":"2026-02-25T17:09:33.825886Z","steps":["trace[1195625748] 'agreement among raft nodes before linearized reading' (duration: 645.910657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.825911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:33.179892Z","time spent":"646.015059ms","remote":"127.0.0.1:55340","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-25T17:09:33.82653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.714005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:09:33.826613Z","caller":"traceutil/trace.go:171","msg":"trace[134473762] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1818; }","duration":"108.860449ms","start":"2026-02-25T17:09:33.717734Z","end":"2026-02-25T17:09:33.826594Z","steps":["trace[134473762] 'agreement among raft nodes before linearized reading' (duration: 108.698025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.826635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.352843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T17:09:33.826667Z","caller":"traceutil/trace.go:171","msg":"trace[646317495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1818; }","duration":"381.423425ms","start":"2026-02-25T17:09:33.445234Z","end":"2026-02-25T17:09:33.826658Z","steps":["trace[646317495] 'agreement among raft nodes before linearized reading' (duration: 381.329513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:33.826686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:33.445214Z","time spent":"381.467427ms","remote":"127.0.0.1:45682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T17:09:36.285045Z","caller":"traceutil/trace.go:171","msg":"trace[2083329771] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"134.988598ms","start":"2026-02-25T17:09:36.150018Z","end":"2026-02-25T17:09:36.285006Z","steps":["trace[2083329771] 'read index received' (duration: 97.610251ms)","trace[2083329771] 'applied index is now lower than readState.Index' (duration: 37.376867ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:09:36.285159Z","caller":"traceutil/trace.go:171","msg":"trace[491761776] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"235.909145ms","start":"2026-02-25T17:09:36.04921Z","end":"2026-02-25T17:09:36.28512Z","steps":["trace[491761776] 'process raft request' (duration: 198.486786ms)","trace[491761776] 'compare' (duration: 37.136412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:09:36.285358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.312536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-25T17:09:36.285408Z","caller":"traceutil/trace.go:171","msg":"trace[1358551638] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1835; }","duration":"135.429189ms","start":"2026-02-25T17:09:36.149967Z","end":"2026-02-25T17:09:36.285396Z","steps":["trace[1358551638] 'agreement among raft nodes before linearized reading' (duration: 135.188813ms)"],"step_count":1}