{"level":"warn","ts":"2026-04-19T11:03:11.644285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T11:03:11.644359Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.223: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.223:2380","--initial-cluster=instance=https://199.19.213.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.223: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-04-19T11:03:11.644431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T11:03:11.644442Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-04-19T11:03:11.644461Z","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-04-19T11:03:11.645793Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"]} {"level":"info","ts":"2026-04-19T11:03:11.645901Z","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.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.223: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-04-19T11:03:11.656064Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.901957ms"} {"level":"info","ts":"2026-04-19T11:03:11.682528Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"12dd93b2fab6fcb","cluster-id":"8ac3bdb9c2c26b53"} {"level":"info","ts":"2026-04-19T11:03:11.682727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T11:03:11.682768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 0"} {"level":"info","ts":"2026-04-19T11:03:11.682789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 12dd93b2fab6fcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T11:03:11.682812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 1"} {"level":"info","ts":"2026-04-19T11:03:11.68287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"warn","ts":"2026-04-19T11:03:11.692301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T11:03:11.693977Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T11:03:11.696124Z","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-04-19T11:03:11.698304Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"12dd93b2fab6fcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T11:03:11.698942Z","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-04-19T11:03:11.699069Z","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-04-19T11:03:11.699087Z","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-04-19T11:03:11.699149Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"12dd93b2fab6fcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T11:03:11.700526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"info","ts":"2026-04-19T11:03:11.700754Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","added-peer-id":"12dd93b2fab6fcb","added-peer-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-04-19T11:03:11.708552Z","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-04-19T11:03:11.708741Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"12dd93b2fab6fcb","initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T11:03:11.708782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T11:03:11.708861Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-04-19T11:03:11.708907Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-04-19T11:03:12.483802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T11:03:12.483865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T11:03:12.483901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgPreVoteResp from 12dd93b2fab6fcb at term 1"} {"level":"info","ts":"2026-04-19T11:03:12.48392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became candidate at term 2"} {"level":"info","ts":"2026-04-19T11:03:12.483931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgVoteResp from 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-04-19T11:03:12.483947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became leader at term 2"} {"level":"info","ts":"2026-04-19T11:03:12.48396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 12dd93b2fab6fcb elected leader 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-04-19T11:03:12.586569Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"12dd93b2fab6fcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.223:2379]}","request-path":"/0/members/12dd93b2fab6fcb/attributes","cluster-id":"8ac3bdb9c2c26b53","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T11:03:12.586755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:03:12.586791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:03:12.587129Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T11:03:12.58724Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T11:03:12.587359Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:03:12.589917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T11:03:12.591325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.223:2379"} {"level":"info","ts":"2026-04-19T11:03:12.638265Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:03:12.638418Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:03:12.638466Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:03:16.217496Z","caller":"traceutil/trace.go:171","msg":"trace[415093717] linearizableReadLoop","detail":"{readStateIndex:228; appliedIndex:227; }","duration":"105.916108ms","start":"2026-04-19T11:03:16.111553Z","end":"2026-04-19T11:03:16.217469Z","steps":["trace[415093717] 'read index received' (duration: 57.785165ms)","trace[415093717] 'applied index is now lower than readState.Index' (duration: 48.129913ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:16.217653Z","caller":"traceutil/trace.go:171","msg":"trace[791614076] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"160.44284ms","start":"2026-04-19T11:03:16.057183Z","end":"2026-04-19T11:03:16.217625Z","steps":["trace[791614076] 'process raft request' (duration: 112.346148ms)","trace[791614076] 'compare' (duration: 47.805849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:16.217747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.167119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-19T11:03:16.217886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.145384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-19T11:03:16.218142Z","caller":"traceutil/trace.go:171","msg":"trace[1826044952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:223; }","duration":"106.603427ms","start":"2026-04-19T11:03:16.111516Z","end":"2026-04-19T11:03:16.218119Z","steps":["trace[1826044952] 'agreement among raft nodes before linearized reading' (duration: 106.114106ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:16.218154Z","caller":"traceutil/trace.go:171","msg":"trace[343853867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:223; }","duration":"105.545881ms","start":"2026-04-19T11:03:16.112586Z","end":"2026-04-19T11:03:16.218132Z","steps":["trace[343853867] 'agreement among raft nodes before linearized reading' (duration: 105.117542ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:16.358181Z","caller":"traceutil/trace.go:171","msg":"trace[1890763696] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"132.121297ms","start":"2026-04-19T11:03:16.226034Z","end":"2026-04-19T11:03:16.358155Z","steps":["trace[1890763696] 'process raft request' (duration: 132.053954ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:16.358323Z","caller":"traceutil/trace.go:171","msg":"trace[1546985118] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"133.232154ms","start":"2026-04-19T11:03:16.225054Z","end":"2026-04-19T11:03:16.358286Z","steps":["trace[1546985118] 'process raft request' (duration: 127.474774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:16.577236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.079279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-19T11:03:16.577368Z","caller":"traceutil/trace.go:171","msg":"trace[530568995] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:229; }","duration":"119.199305ms","start":"2026-04-19T11:03:16.4581Z","end":"2026-04-19T11:03:16.577299Z","steps":["trace[530568995] 'range keys from in-memory index tree' (duration: 119.006456ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:26.7301Z","caller":"traceutil/trace.go:171","msg":"trace[1020651999] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"303.95215ms","start":"2026-04-19T11:03:26.426128Z","end":"2026-04-19T11:03:26.73008Z","steps":["trace[1020651999] 'read index received' (duration: 303.785993ms)","trace[1020651999] 'applied index is now lower than readState.Index' (duration: 165.567µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:26.730265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.126296ms","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-04-19T11:03:26.730291Z","caller":"traceutil/trace.go:171","msg":"trace[1419736672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"304.206799ms","start":"2026-04-19T11:03:26.426076Z","end":"2026-04-19T11:03:26.730283Z","steps":["trace[1419736672] 'agreement among raft nodes before linearized reading' (duration: 304.104095ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:26.730226Z","caller":"traceutil/trace.go:171","msg":"trace[1331711324] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"336.273987ms","start":"2026-04-19T11:03:26.393919Z","end":"2026-04-19T11:03:26.730193Z","steps":["trace[1331711324] 'process raft request' (duration: 336.049918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:26.730316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:26.426Z","time spent":"304.310083ms","remote":"127.0.0.1:39976","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-04-19T11:03:26.731092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:26.393891Z","time spent":"336.503626ms","remote":"127.0.0.1:39976","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-04-19T11:03:30.192045Z","caller":"traceutil/trace.go:171","msg":"trace[1659988555] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"161.276031ms","start":"2026-04-19T11:03:30.030728Z","end":"2026-04-19T11:03:30.192004Z","steps":["trace[1659988555] 'process raft request' (duration: 161.128076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:37.358307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:37.858389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:37.953504Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.101235955s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:03:37.953867Z","caller":"traceutil/trace.go:171","msg":"trace[1882719768] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"1.096009402s","start":"2026-04-19T11:03:36.857837Z","end":"2026-04-19T11:03:37.953846Z","steps":["trace[1882719768] 'read index received' (duration: 1.095819585s)","trace[1882719768] 'applied index is now lower than readState.Index' (duration: 188.807µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:37.95403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088642393s","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-04-19T11:03:37.95404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096186918s","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-04-19T11:03:37.954085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.059478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:03:37.954102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.477608ms","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-04-19T11:03:37.954113Z","caller":"traceutil/trace.go:171","msg":"trace[850392041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"1.096294342s","start":"2026-04-19T11:03:36.857798Z","end":"2026-04-19T11:03:37.954093Z","steps":["trace[850392041] 'agreement among raft nodes before linearized reading' (duration: 1.096130287s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:37.954124Z","caller":"traceutil/trace.go:171","msg":"trace[928491144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"980.53957ms","start":"2026-04-19T11:03:36.973575Z","end":"2026-04-19T11:03:37.954115Z","steps":["trace[928491144] 'agreement among raft nodes before linearized reading' (duration: 980.506179ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:37.954126Z","caller":"traceutil/trace.go:171","msg":"trace[1143465167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"499.137021ms","start":"2026-04-19T11:03:37.454976Z","end":"2026-04-19T11:03:37.954113Z","steps":["trace[1143465167] 'agreement among raft nodes before linearized reading' (duration: 499.071709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:37.954147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:36.857787Z","time spent":"1.096353284s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:03:37.954151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:37.454958Z","time spent":"499.187832ms","remote":"127.0.0.1:54862","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-04-19T11:03:37.954151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:36.973556Z","time spent":"980.590112ms","remote":"127.0.0.1:39976","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-04-19T11:03:37.954214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047353859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:03:37.954067Z","caller":"traceutil/trace.go:171","msg":"trace[1775657648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"1.088737627s","start":"2026-04-19T11:03:36.865321Z","end":"2026-04-19T11:03:37.954059Z","steps":["trace[1775657648] 'agreement among raft nodes before linearized reading' (duration: 1.088656484s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:37.954254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:36.8653Z","time spent":"1.088947965s","remote":"127.0.0.1:39976","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-04-19T11:03:37.954263Z","caller":"traceutil/trace.go:171","msg":"trace[1353602930] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:1; response_revision:462; }","duration":"1.047448102s","start":"2026-04-19T11:03:36.906796Z","end":"2026-04-19T11:03:37.954244Z","steps":["trace[1353602930] 'agreement among raft nodes before linearized reading' (duration: 1.047347989s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:37.954301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:36.906782Z","time spent":"1.047511464s","remote":"127.0.0.1:39700","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.223\" "} {"level":"info","ts":"2026-04-19T11:03:37.954478Z","caller":"traceutil/trace.go:171","msg":"trace[706395077] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"1.102296591s","start":"2026-04-19T11:03:36.852169Z","end":"2026-04-19T11:03:37.954466Z","steps":["trace[706395077] 'process raft request' (duration: 1.101555016s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:37.954613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:36.852117Z","time spent":"1.102392644s","remote":"127.0.0.1:39976","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-04-19T11:03:40.239103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.287901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:03:40.239286Z","caller":"traceutil/trace.go:171","msg":"trace[1995495179] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"183.791266ms","start":"2026-04-19T11:03:40.055482Z","end":"2026-04-19T11:03:40.239273Z","steps":["trace[1995495179] 'process raft request' (duration: 183.755365ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:40.239524Z","caller":"traceutil/trace.go:171","msg":"trace[1872129429] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"184.781079ms","start":"2026-04-19T11:03:40.0547Z","end":"2026-04-19T11:03:40.239481Z","steps":["trace[1872129429] 'process raft request' (duration: 184.487409ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:40.239419Z","caller":"traceutil/trace.go:171","msg":"trace[567766842] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"184.919304ms","start":"2026-04-19T11:03:40.054464Z","end":"2026-04-19T11:03:40.239383Z","steps":["trace[567766842] 'process raft request' (duration: 81.160267ms)","trace[567766842] 'compare' (duration: 103.197208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:42.360305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.86778ms","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-04-19T11:03:42.360388Z","caller":"traceutil/trace.go:171","msg":"trace[289327790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"116.989914ms","start":"2026-04-19T11:03:42.243378Z","end":"2026-04-19T11:03:42.360367Z","steps":["trace[289327790] 'agreement among raft nodes before linearized reading' (duration: 116.830459ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:42.360147Z","caller":"traceutil/trace.go:171","msg":"trace[74962551] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"116.672734ms","start":"2026-04-19T11:03:42.243411Z","end":"2026-04-19T11:03:42.360084Z","steps":["trace[74962551] 'read index received' (duration: 31.396166ms)","trace[74962551] 'applied index is now lower than readState.Index' (duration: 85.274988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:42.361076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.227551ms","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-04-19T11:03:42.361117Z","caller":"traceutil/trace.go:171","msg":"trace[1695394599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"117.355746ms","start":"2026-04-19T11:03:42.24375Z","end":"2026-04-19T11:03:42.361106Z","steps":["trace[1695394599] 'agreement among raft nodes before linearized reading' (duration: 117.225632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:42.361383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.650675ms","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-04-19T11:03:42.361418Z","caller":"traceutil/trace.go:171","msg":"trace[32304424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"117.725438ms","start":"2026-04-19T11:03:42.24368Z","end":"2026-04-19T11:03:42.361405Z","steps":["trace[32304424] 'agreement among raft nodes before linearized reading' (duration: 117.256382ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:42.587435Z","caller":"traceutil/trace.go:171","msg":"trace[1610863447] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"222.634052ms","start":"2026-04-19T11:03:42.364781Z","end":"2026-04-19T11:03:42.587415Z","steps":["trace[1610863447] 'process raft request' (duration: 158.586103ms)","trace[1610863447] 'compare' (duration: 63.847212ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:42.587621Z","caller":"traceutil/trace.go:171","msg":"trace[790498891] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"222.780136ms","start":"2026-04-19T11:03:42.364815Z","end":"2026-04-19T11:03:42.587595Z","steps":["trace[790498891] 'process raft request' (duration: 222.567939ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:44.976378Z","caller":"traceutil/trace.go:171","msg":"trace[2022221346] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"135.069111ms","start":"2026-04-19T11:03:44.841293Z","end":"2026-04-19T11:03:44.976362Z","steps":["trace[2022221346] 'process raft request' (duration: 134.956587ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.023077Z","caller":"traceutil/trace.go:171","msg":"trace[1215106270] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"116.273962ms","start":"2026-04-19T11:03:45.906782Z","end":"2026-04-19T11:03:46.023056Z","steps":["trace[1215106270] 'process raft request' (duration: 116.145667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:46.171081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.898659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:03:46.171339Z","caller":"traceutil/trace.go:171","msg":"trace[1797062527] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"261.065173ms","start":"2026-04-19T11:03:45.910217Z","end":"2026-04-19T11:03:46.171282Z","steps":["trace[1797062527] 'process raft request' (duration: 156.890606ms)","trace[1797062527] 'compare' (duration: 103.741064ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:46.171446Z","caller":"traceutil/trace.go:171","msg":"trace[620583056] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"261.050644ms","start":"2026-04-19T11:03:45.910362Z","end":"2026-04-19T11:03:46.171412Z","steps":["trace[620583056] 'process raft request' (duration: 260.825877ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.171488Z","caller":"traceutil/trace.go:171","msg":"trace[1583569983] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"251.189898ms","start":"2026-04-19T11:03:45.920275Z","end":"2026-04-19T11:03:46.171465Z","steps":["trace[1583569983] 'process raft request' (duration: 250.9695ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.171639Z","caller":"traceutil/trace.go:171","msg":"trace[993693442] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"251.277061ms","start":"2026-04-19T11:03:45.92035Z","end":"2026-04-19T11:03:46.171627Z","steps":["trace[993693442] 'process raft request' (duration: 250.94148ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.172326Z","caller":"traceutil/trace.go:171","msg":"trace[42956166] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"251.55803ms","start":"2026-04-19T11:03:45.92075Z","end":"2026-04-19T11:03:46.172308Z","steps":["trace[42956166] 'process raft request' (duration: 250.715613ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173017Z","caller":"traceutil/trace.go:171","msg":"trace[747918967] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"250.642429ms","start":"2026-04-19T11:03:45.922354Z","end":"2026-04-19T11:03:46.172997Z","steps":["trace[747918967] 'process raft request' (duration: 250.423242ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173036Z","caller":"traceutil/trace.go:171","msg":"trace[1586866274] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"251.365973ms","start":"2026-04-19T11:03:45.921654Z","end":"2026-04-19T11:03:46.17302Z","steps":["trace[1586866274] 'process raft request' (duration: 250.870117ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173118Z","caller":"traceutil/trace.go:171","msg":"trace[1928542959] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"245.924109ms","start":"2026-04-19T11:03:45.927184Z","end":"2026-04-19T11:03:46.173108Z","steps":["trace[1928542959] 'process raft request' (duration: 245.670502ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173267Z","caller":"traceutil/trace.go:171","msg":"trace[1024249902] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"231.331792ms","start":"2026-04-19T11:03:45.941926Z","end":"2026-04-19T11:03:46.173258Z","steps":["trace[1024249902] 'process raft request' (duration: 230.981591ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173435Z","caller":"traceutil/trace.go:171","msg":"trace[1161798387] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"204.484324ms","start":"2026-04-19T11:03:45.968942Z","end":"2026-04-19T11:03:46.173426Z","steps":["trace[1161798387] 'process raft request' (duration: 204.01955ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173624Z","caller":"traceutil/trace.go:171","msg":"trace[1584103489] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"144.554255ms","start":"2026-04-19T11:03:46.029061Z","end":"2026-04-19T11:03:46.173616Z","steps":["trace[1584103489] 'process raft request' (duration: 144.236255ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173814Z","caller":"traceutil/trace.go:171","msg":"trace[1007084587] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"143.520184ms","start":"2026-04-19T11:03:46.030271Z","end":"2026-04-19T11:03:46.173791Z","steps":["trace[1007084587] 'process raft request' (duration: 143.180493ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.173755Z","caller":"traceutil/trace.go:171","msg":"trace[114605105] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"143.172553ms","start":"2026-04-19T11:03:46.03049Z","end":"2026-04-19T11:03:46.173662Z","steps":["trace[114605105] 'process raft request' (duration: 143.128541ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.209793Z","caller":"traceutil/trace.go:171","msg":"trace[680926301] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"133.175827ms","start":"2026-04-19T11:03:46.075757Z","end":"2026-04-19T11:03:46.208933Z","steps":["trace[680926301] 'process raft request' (duration: 132.763494ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.343507Z","caller":"traceutil/trace.go:171","msg":"trace[987111818] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"126.925988ms","start":"2026-04-19T11:03:46.216549Z","end":"2026-04-19T11:03:46.343475Z","steps":["trace[987111818] 'process raft request' (duration: 73.389293ms)","trace[987111818] 'compare' (duration: 53.102982ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:46.434902Z","caller":"traceutil/trace.go:171","msg":"trace[1850048575] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"128.070575ms","start":"2026-04-19T11:03:46.306806Z","end":"2026-04-19T11:03:46.434876Z","steps":["trace[1850048575] 'process raft request' (duration: 127.888408ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.435046Z","caller":"traceutil/trace.go:171","msg":"trace[1799139342] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"128.033973ms","start":"2026-04-19T11:03:46.306978Z","end":"2026-04-19T11:03:46.435012Z","steps":["trace[1799139342] 'process raft request' (duration: 127.804466ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.435606Z","caller":"traceutil/trace.go:171","msg":"trace[1609165071] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"143.481573ms","start":"2026-04-19T11:03:46.29135Z","end":"2026-04-19T11:03:46.434831Z","steps":["trace[1609165071] 'process raft request' (duration: 143.184542ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.562565Z","caller":"traceutil/trace.go:171","msg":"trace[2001084620] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:566; }","duration":"127.546628ms","start":"2026-04-19T11:03:46.434981Z","end":"2026-04-19T11:03:46.562527Z","steps":["trace[2001084620] 'read index received' (duration: 77.785592ms)","trace[2001084620] 'applied index is now lower than readState.Index' (duration: 49.759546ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:46.56264Z","caller":"traceutil/trace.go:171","msg":"trace[552736914] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"190.098808ms","start":"2026-04-19T11:03:46.372501Z","end":"2026-04-19T11:03:46.562599Z","steps":["trace[552736914] 'process raft request' (duration: 189.936333ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.562698Z","caller":"traceutil/trace.go:171","msg":"trace[23534750] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"191.533004ms","start":"2026-04-19T11:03:46.371146Z","end":"2026-04-19T11:03:46.562679Z","steps":["trace[23534750] 'process raft request' (duration: 141.52774ms)","trace[23534750] 'compare' (duration: 49.499788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:46.563045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.169893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-19T11:03:46.563108Z","caller":"traceutil/trace.go:171","msg":"trace[975939335] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"198.246995ms","start":"2026-04-19T11:03:46.364842Z","end":"2026-04-19T11:03:46.563089Z","steps":["trace[975939335] 'agreement among raft nodes before linearized reading' (duration: 197.976197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:46.563225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.139002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-04-19T11:03:46.563294Z","caller":"traceutil/trace.go:171","msg":"trace[1611628030] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"198.272026ms","start":"2026-04-19T11:03:46.365003Z","end":"2026-04-19T11:03:46.563275Z","steps":["trace[1611628030] 'agreement among raft nodes before linearized reading' (duration: 197.708739ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.563911Z","caller":"traceutil/trace.go:171","msg":"trace[1275652448] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"120.789825ms","start":"2026-04-19T11:03:46.443101Z","end":"2026-04-19T11:03:46.56389Z","steps":["trace[1275652448] 'process raft request' (duration: 120.383552ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.563974Z","caller":"traceutil/trace.go:171","msg":"trace[1809271759] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"118.222334ms","start":"2026-04-19T11:03:46.445712Z","end":"2026-04-19T11:03:46.563934Z","steps":["trace[1809271759] 'process raft request' (duration: 117.963186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:46.564048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.086446ms","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-04-19T11:03:46.564131Z","caller":"traceutil/trace.go:171","msg":"trace[671087011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"123.273792ms","start":"2026-04-19T11:03:46.44084Z","end":"2026-04-19T11:03:46.564114Z","steps":["trace[671087011] 'agreement among raft nodes before linearized reading' (duration: 122.989354ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:46.564375Z","caller":"traceutil/trace.go:171","msg":"trace[1924813650] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"113.042559ms","start":"2026-04-19T11:03:46.451319Z","end":"2026-04-19T11:03:46.564362Z","steps":["trace[1924813650] 'process raft request' (duration: 112.44297ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:47.13959Z","caller":"traceutil/trace.go:171","msg":"trace[290691798] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"142.520551ms","start":"2026-04-19T11:03:46.997044Z","end":"2026-04-19T11:03:47.139565Z","steps":["trace[290691798] 'process raft request' (duration: 117.238888ms)","trace[290691798] 'compare' (duration: 25.116578ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:47.273269Z","caller":"traceutil/trace.go:171","msg":"trace[1242353633] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"133.557907ms","start":"2026-04-19T11:03:47.139691Z","end":"2026-04-19T11:03:47.273249Z","steps":["trace[1242353633] 'process raft request' (duration: 104.458204ms)","trace[1242353633] 'compare' (duration: 28.611208ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:47.273369Z","caller":"traceutil/trace.go:171","msg":"trace[516608111] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"111.171666ms","start":"2026-04-19T11:03:47.162164Z","end":"2026-04-19T11:03:47.273336Z","steps":["trace[516608111] 'process raft request' (duration: 110.913718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:49.453846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999909949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-19T11:03:49.453986Z","caller":"traceutil/trace.go:171","msg":"trace[1224756217] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000102585s","start":"2026-04-19T11:03:47.45386Z","end":"2026-04-19T11:03:49.453963Z","steps":["trace[1224756217] 'agreement among raft nodes before linearized reading' (duration: 1.99993295s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:49.454048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:47.453847Z","time spent":"2.000187947s","remote":"127.0.0.1:54862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:03:49.795232Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.327416302s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T11:03:51.290477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494673978s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:03:51.290573Z","caller":"traceutil/trace.go:171","msg":"trace[394578783] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"3.836665287s","start":"2026-04-19T11:03:47.453893Z","end":"2026-04-19T11:03:51.290558Z","steps":["trace[394578783] 'read index received' (duration: 13.995029ms)","trace[394578783] 'applied index is now lower than readState.Index' (duration: 3.822669158s)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:51.290883Z","caller":"traceutil/trace.go:171","msg":"trace[82572211] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"3.852381048s","start":"2026-04-19T11:03:47.438488Z","end":"2026-04-19T11:03:51.290869Z","steps":["trace[82572211] 'process raft request' (duration: 2.357236286s)","trace[82572211] 'compare' (duration: 1.494339738s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:51.290944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:47.438479Z","time spent":"3.852430219s","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:03:51.298751Z","caller":"traceutil/trace.go:171","msg":"trace[1850664029] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"3.822235644s","start":"2026-04-19T11:03:47.476485Z","end":"2026-04-19T11:03:51.298721Z","steps":["trace[1850664029] 'process raft request' (duration: 3.821817962s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.298912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:47.476469Z","time spent":"3.822361328s","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:03:51.300586Z","caller":"traceutil/trace.go:171","msg":"trace[244768481] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"3.725618443s","start":"2026-04-19T11:03:47.574924Z","end":"2026-04-19T11:03:51.300543Z","steps":["trace[244768481] 'process raft request' (duration: 3.723624053s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:51.300838Z","caller":"traceutil/trace.go:171","msg":"trace[1633284667] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"2.000713303s","start":"2026-04-19T11:03:49.300081Z","end":"2026-04-19T11:03:51.300794Z","steps":["trace[1633284667] 'process raft request' (duration: 1.998899939s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.300995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:49.300063Z","time spent":"2.000862859s","remote":"127.0.0.1:39976","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-04-19T11:03:51.301148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954026348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-19T11:03:51.301195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:47.574891Z","time spent":"3.725819739s","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:03:51.301379Z","caller":"traceutil/trace.go:171","msg":"trace[2071991501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"2.954150641s","start":"2026-04-19T11:03:48.347062Z","end":"2026-04-19T11:03:51.301213Z","steps":["trace[2071991501] 'agreement among raft nodes before linearized reading' (duration: 2.951966245s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.301444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:48.347044Z","time spent":"2.954384528s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T11:03:51.30131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.59086393s","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-04-19T11:03:51.301547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.846589603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:51.301638Z","caller":"traceutil/trace.go:171","msg":"trace[1923757598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"1.846697397s","start":"2026-04-19T11:03:49.454905Z","end":"2026-04-19T11:03:51.301602Z","steps":["trace[1923757598] 'agreement among raft nodes before linearized reading' (duration: 1.84645648s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.301698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:49.454865Z","time spent":"1.84681543s","remote":"127.0.0.1:54846","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-04-19T11:03:51.301834Z","caller":"traceutil/trace.go:171","msg":"trace[376725330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"2.59119603s","start":"2026-04-19T11:03:48.710379Z","end":"2026-04-19T11:03:51.301575Z","steps":["trace[376725330] 'agreement among raft nodes before linearized reading' (duration: 2.590761447s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.301922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.537912274s","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-04-19T11:03:51.301989Z","caller":"traceutil/trace.go:171","msg":"trace[980262615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"2.538002087s","start":"2026-04-19T11:03:48.763961Z","end":"2026-04-19T11:03:51.301963Z","steps":["trace[980262615] 'agreement among raft nodes before linearized reading' (duration: 2.537827022s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.302023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:48.763941Z","time spent":"2.538072979s","remote":"127.0.0.1:39976","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-04-19T11:03:51.301988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:48.710358Z","time spent":"2.59154165s","remote":"127.0.0.1:39976","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-04-19T11:03:51.302296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.710527975s","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-04-19T11:03:51.302363Z","caller":"traceutil/trace.go:171","msg":"trace[1854896193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"2.710608388s","start":"2026-04-19T11:03:48.591728Z","end":"2026-04-19T11:03:51.302337Z","steps":["trace[1854896193] 'agreement among raft nodes before linearized reading' (duration: 2.710463794s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.302405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:48.591715Z","time spent":"2.71068034s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:03:51.302872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.385675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:51.302923Z","caller":"traceutil/trace.go:171","msg":"trace[400330035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"953.464587ms","start":"2026-04-19T11:03:50.349439Z","end":"2026-04-19T11:03:51.302904Z","steps":["trace[400330035] 'agreement among raft nodes before linearized reading' (duration: 949.596411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:51.303024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:50.34941Z","time spent":"953.57209ms","remote":"127.0.0.1:54862","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-04-19T11:03:58.967507Z","caller":"traceutil/trace.go:171","msg":"trace[1637709520] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"100.535344ms","start":"2026-04-19T11:03:58.866944Z","end":"2026-04-19T11:03:58.96748Z","steps":["trace[1637709520] 'process raft request' (duration: 14.172641ms)","trace[1637709520] 'compare' (duration: 86.168137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:59.667389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025662,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:03:59.67767Z","caller":"traceutil/trace.go:171","msg":"trace[1693761974] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"510.309952ms","start":"2026-04-19T11:03:59.167338Z","end":"2026-04-19T11:03:59.677648Z","steps":["trace[1693761974] 'read index received' (duration: 509.640644ms)","trace[1693761974] 'applied index is now lower than readState.Index' (duration: 668.388µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:59.677896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.537349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-79zvd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T11:03:59.677935Z","caller":"traceutil/trace.go:171","msg":"trace[251728303] range","detail":"{range_begin:/registry/pods/kube-system/cilium-79zvd; range_end:; response_count:1; response_revision:632; }","duration":"510.641322ms","start":"2026-04-19T11:03:59.167284Z","end":"2026-04-19T11:03:59.677925Z","steps":["trace[251728303] 'agreement among raft nodes before linearized reading' (duration: 510.507838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.677963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.167266Z","time spent":"510.689983ms","remote":"127.0.0.1:39878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-79zvd\" "} {"level":"warn","ts":"2026-04-19T11:03:59.677987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.934137ms","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-04-19T11:03:59.677736Z","caller":"traceutil/trace.go:171","msg":"trace[381610324] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"532.087044ms","start":"2026-04-19T11:03:59.145605Z","end":"2026-04-19T11:03:59.677692Z","steps":["trace[381610324] 'process raft request' (duration: 531.539359ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:59.67807Z","caller":"traceutil/trace.go:171","msg":"trace[494373098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"320.127823ms","start":"2026-04-19T11:03:59.357921Z","end":"2026-04-19T11:03:59.678048Z","steps":["trace[494373098] 'agreement among raft nodes before linearized reading' (duration: 319.876946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.678121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.3579Z","time spent":"320.212334ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:03:59.678261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.908392ms","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-04-19T11:03:59.678195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.922499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:59.67836Z","caller":"traceutil/trace.go:171","msg":"trace[1258120324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"312.031216ms","start":"2026-04-19T11:03:59.366309Z","end":"2026-04-19T11:03:59.67834Z","steps":["trace[1258120324] 'agreement among raft nodes before linearized reading' (duration: 311.602524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.678293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.365977ms","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-04-19T11:03:59.678468Z","caller":"traceutil/trace.go:171","msg":"trace[2060910174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"316.580583ms","start":"2026-04-19T11:03:59.361878Z","end":"2026-04-19T11:03:59.678459Z","steps":["trace[2060910174] 'agreement among raft nodes before linearized reading' (duration: 316.10472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.678315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.145571Z","time spent":"532.569967ms","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:03:59.678521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.366296Z","time spent":"312.181339ms","remote":"127.0.0.1:39976","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-04-19T11:03:59.678625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.086851ms","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-04-19T11:03:59.678502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.361861Z","time spent":"316.631275ms","remote":"127.0.0.1:39976","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-04-19T11:03:59.678452Z","caller":"traceutil/trace.go:171","msg":"trace[722643524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"223.214317ms","start":"2026-04-19T11:03:59.455163Z","end":"2026-04-19T11:03:59.678378Z","steps":["trace[722643524] 'agreement among raft nodes before linearized reading' (duration: 222.771174ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:59.678712Z","caller":"traceutil/trace.go:171","msg":"trace[1774780692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"310.133852ms","start":"2026-04-19T11:03:59.368525Z","end":"2026-04-19T11:03:59.678659Z","steps":["trace[1774780692] 'agreement among raft nodes before linearized reading' (duration: 309.346611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.678791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.368497Z","time spent":"310.250596ms","remote":"127.0.0.1:39976","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-04-19T11:04:00.277322Z","caller":"traceutil/trace.go:171","msg":"trace[750400431] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"593.963119ms","start":"2026-04-19T11:03:59.683333Z","end":"2026-04-19T11:04:00.277296Z","steps":["trace[750400431] 'process raft request' (duration: 593.743303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:00.27751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.68328Z","time spent":"594.127944ms","remote":"127.0.0.1:39976","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-04-19T11:04:00.277814Z","caller":"traceutil/trace.go:171","msg":"trace[1633334773] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"594.329419ms","start":"2026-04-19T11:03:59.683458Z","end":"2026-04-19T11:04:00.277787Z","steps":["trace[1633334773] 'process raft request' (duration: 593.777004ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:00.277845Z","caller":"traceutil/trace.go:171","msg":"trace[1243208667] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"580.366088ms","start":"2026-04-19T11:03:59.697459Z","end":"2026-04-19T11:04:00.277825Z","steps":["trace[1243208667] 'process raft request' (duration: 580.296326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:00.277981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.683446Z","time spent":"594.428351ms","remote":"127.0.0.1:39976","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-04-19T11:04:00.277887Z","caller":"traceutil/trace.go:171","msg":"trace[279411913] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"594.049511ms","start":"2026-04-19T11:03:59.683817Z","end":"2026-04-19T11:04:00.277866Z","steps":["trace[279411913] 'process raft request' (duration: 593.826595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:00.278267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.69734Z","time spent":"580.609435ms","remote":"127.0.0.1:39878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:00.278331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.683805Z","time spent":"594.443252ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:00.612492Z","caller":"traceutil/trace.go:171","msg":"trace[2124022879] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"155.729755ms","start":"2026-04-19T11:04:00.456738Z","end":"2026-04-19T11:04:00.612468Z","steps":["trace[2124022879] 'read index received' (duration: 155.52848ms)","trace[2124022879] 'applied index is now lower than readState.Index' (duration: 200.415µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:00.612602Z","caller":"traceutil/trace.go:171","msg":"trace[648465259] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"875.751022ms","start":"2026-04-19T11:03:59.736803Z","end":"2026-04-19T11:04:00.612554Z","steps":["trace[648465259] 'process raft request' (duration: 875.517916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:00.612821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.069626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:00.612908Z","caller":"traceutil/trace.go:171","msg":"trace[1431789639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"156.23336ms","start":"2026-04-19T11:04:00.456658Z","end":"2026-04-19T11:04:00.612891Z","steps":["trace[1431789639] 'agreement among raft nodes before linearized reading' (duration: 156.003173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:00.612825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.736779Z","time spent":"875.941189ms","remote":"127.0.0.1:40224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:04:01.955744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:04:02.456581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:04:02.90035Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.853297217s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:04:02.902085Z","caller":"traceutil/trace.go:171","msg":"trace[356396278] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.855854328s","start":"2026-04-19T11:04:01.045601Z","end":"2026-04-19T11:04:02.901455Z","steps":["trace[356396278] 'process raft request' (duration: 1.855332583s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:02.902274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:01.045574Z","time spent":"1.856589599s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:03.186755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.046138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:04:03.187501Z","caller":"traceutil/trace.go:171","msg":"trace[971302699] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.562951051s","start":"2026-04-19T11:04:01.624523Z","end":"2026-04-19T11:04:03.187474Z","steps":["trace[971302699] 'process raft request' (duration: 1.388112421s)","trace[971302699] 'compare' (duration: 173.920564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:03.187651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:01.624499Z","time spent":"1.563079725s","remote":"127.0.0.1:39976","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-04-19T11:04:03.285946Z","caller":"traceutil/trace.go:171","msg":"trace[22416877] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"1.830471719s","start":"2026-04-19T11:04:01.455421Z","end":"2026-04-19T11:04:03.285893Z","steps":["trace[22416877] 'read index received' (duration: 1.445147051s)","trace[22416877] 'applied index is now lower than readState.Index' (duration: 385.323878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:03.286107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830670394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:03.286172Z","caller":"traceutil/trace.go:171","msg":"trace[562549838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.830781097s","start":"2026-04-19T11:04:01.455372Z","end":"2026-04-19T11:04:03.286153Z","steps":["trace[562549838] 'agreement among raft nodes before linearized reading' (duration: 1.830677685s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:03.286186Z","caller":"traceutil/trace.go:171","msg":"trace[1586536524] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"317.046444ms","start":"2026-04-19T11:04:02.969105Z","end":"2026-04-19T11:04:03.286151Z","steps":["trace[1586536524] 'process raft request' (duration: 316.698635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:03.28635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:02.969058Z","time spent":"317.22003ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:04:03.286213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:01.455351Z","time spent":"1.83085325s","remote":"127.0.0.1:54846","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-04-19T11:04:03.322703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039635365s","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-04-19T11:04:03.322779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039467641s","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-04-19T11:04:03.322933Z","caller":"traceutil/trace.go:171","msg":"trace[1527281699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"1.039653005s","start":"2026-04-19T11:04:02.283259Z","end":"2026-04-19T11:04:03.322912Z","steps":["trace[1527281699] 'agreement among raft nodes before linearized reading' (duration: 1.039241254s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:03.323015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:02.283249Z","time spent":"1.039753239s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:04:03.323027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609223969s","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-04-19T11:04:03.323153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040703394s","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-04-19T11:04:03.323211Z","caller":"traceutil/trace.go:171","msg":"trace[2048267797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.040790197s","start":"2026-04-19T11:04:02.282401Z","end":"2026-04-19T11:04:03.323191Z","steps":["trace[2048267797] 'agreement among raft nodes before linearized reading' (duration: 1.040666784s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:03.323196Z","caller":"traceutil/trace.go:171","msg":"trace[807177933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"1.609410004s","start":"2026-04-19T11:04:01.713759Z","end":"2026-04-19T11:04:03.323169Z","steps":["trace[807177933] 'agreement among raft nodes before linearized reading' (duration: 1.609139797s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:03.323267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:02.282379Z","time spent":"1.040874578s","remote":"127.0.0.1:39976","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-04-19T11:04:03.323397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:01.713744Z","time spent":"1.609525917s","remote":"127.0.0.1:39976","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-04-19T11:04:03.322796Z","caller":"traceutil/trace.go:171","msg":"trace[1841452112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.039756999s","start":"2026-04-19T11:04:02.283017Z","end":"2026-04-19T11:04:03.322774Z","steps":["trace[1841452112] 'agreement among raft nodes before linearized reading' (duration: 1.039497692s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:03.323655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:02.283005Z","time spent":"1.040603171s","remote":"127.0.0.1:39976","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-04-19T11:04:05.58571Z","caller":"traceutil/trace.go:171","msg":"trace[288518815] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"244.746388ms","start":"2026-04-19T11:04:05.340944Z","end":"2026-04-19T11:04:05.58569Z","steps":["trace[288518815] 'read index received' (duration: 8.693751ms)","trace[288518815] 'applied index is now lower than readState.Index' (duration: 236.051737ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:05.58573Z","caller":"traceutil/trace.go:171","msg":"trace[1818046047] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"307.758682ms","start":"2026-04-19T11:04:05.277947Z","end":"2026-04-19T11:04:05.585706Z","steps":["trace[1818046047] 'process raft request' (duration: 307.584288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:05.585851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:05.277924Z","time spent":"307.864735ms","remote":"127.0.0.1:39878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:05.58592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.02949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:05.585942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.832973ms","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-04-19T11:04:05.585925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.970143ms","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-04-19T11:04:05.585969Z","caller":"traceutil/trace.go:171","msg":"trace[1238812962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"129.146174ms","start":"2026-04-19T11:04:05.456809Z","end":"2026-04-19T11:04:05.585955Z","steps":["trace[1238812962] 'agreement among raft nodes before linearized reading' (duration: 129.059041ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.585976Z","caller":"traceutil/trace.go:171","msg":"trace[1028047594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"243.898704ms","start":"2026-04-19T11:04:05.342069Z","end":"2026-04-19T11:04:05.585968Z","steps":["trace[1028047594] 'agreement among raft nodes before linearized reading' (duration: 243.75649ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.585994Z","caller":"traceutil/trace.go:171","msg":"trace[823646289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"245.089726ms","start":"2026-04-19T11:04:05.340888Z","end":"2026-04-19T11:04:05.585978Z","steps":["trace[823646289] 'agreement among raft nodes before linearized reading' (duration: 244.896271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:05.586039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.793351ms","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-04-19T11:04:05.586044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.524015ms","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-04-19T11:04:05.586079Z","caller":"traceutil/trace.go:171","msg":"trace[2000481720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"243.859114ms","start":"2026-04-19T11:04:05.342208Z","end":"2026-04-19T11:04:05.586067Z","steps":["trace[2000481720] 'agreement among raft nodes before linearized reading' (duration: 243.73476ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.58612Z","caller":"traceutil/trace.go:171","msg":"trace[1819814002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"243.603876ms","start":"2026-04-19T11:04:05.342482Z","end":"2026-04-19T11:04:05.586086Z","steps":["trace[1819814002] 'agreement among raft nodes before linearized reading' (duration: 243.446912ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.795589Z","caller":"traceutil/trace.go:171","msg":"trace[765890183] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"205.673989ms","start":"2026-04-19T11:04:05.589892Z","end":"2026-04-19T11:04:05.795566Z","steps":["trace[765890183] 'process raft request' (duration: 205.598207ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.795762Z","caller":"traceutil/trace.go:171","msg":"trace[1028570320] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"205.657398ms","start":"2026-04-19T11:04:05.590085Z","end":"2026-04-19T11:04:05.795742Z","steps":["trace[1028570320] 'process raft request' (duration: 205.449093ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.795898Z","caller":"traceutil/trace.go:171","msg":"trace[1479231729] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"205.214366ms","start":"2026-04-19T11:04:05.590678Z","end":"2026-04-19T11:04:05.795892Z","steps":["trace[1479231729] 'process raft request' (duration: 204.99313ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:05.795778Z","caller":"traceutil/trace.go:171","msg":"trace[684640348] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"206.454449ms","start":"2026-04-19T11:04:05.589308Z","end":"2026-04-19T11:04:05.795763Z","steps":["trace[684640348] 'process raft request' (duration: 117.697649ms)","trace[684640348] 'compare' (duration: 88.291138ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:05.795992Z","caller":"traceutil/trace.go:171","msg":"trace[713132604] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"146.541927ms","start":"2026-04-19T11:04:05.649445Z","end":"2026-04-19T11:04:05.795987Z","steps":["trace[713132604] 'process raft request' (duration: 146.414583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:07.476199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.241583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:04:07.476322Z","caller":"traceutil/trace.go:171","msg":"trace[138319032] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:1; response_revision:667; }","duration":"568.389827ms","start":"2026-04-19T11:04:06.907892Z","end":"2026-04-19T11:04:07.476282Z","steps":["trace[138319032] 'range keys from in-memory index tree' (duration: 568.045047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:07.476372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:06.907878Z","time spent":"568.482309ms","remote":"127.0.0.1:39700","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.223\" "} {"level":"info","ts":"2026-04-19T11:04:07.654007Z","caller":"traceutil/trace.go:171","msg":"trace[1502265570] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"137.283508ms","start":"2026-04-19T11:04:07.516701Z","end":"2026-04-19T11:04:07.653985Z","steps":["trace[1502265570] 'read index received' (duration: 137.085692ms)","trace[1502265570] 'applied index is now lower than readState.Index' (duration: 196.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:07.654082Z","caller":"traceutil/trace.go:171","msg":"trace[902873463] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"160.473514ms","start":"2026-04-19T11:04:07.493593Z","end":"2026-04-19T11:04:07.654067Z","steps":["trace[902873463] 'process raft request' (duration: 160.250948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:07.654168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.444042ms","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-04-19T11:04:07.654199Z","caller":"traceutil/trace.go:171","msg":"trace[1181200463] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:669; }","duration":"137.526044ms","start":"2026-04-19T11:04:07.516665Z","end":"2026-04-19T11:04:07.654191Z","steps":["trace[1181200463] 'agreement among raft nodes before linearized reading' (duration: 137.406711ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:07.927234Z","caller":"traceutil/trace.go:171","msg":"trace[1113068083] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"122.899702ms","start":"2026-04-19T11:04:07.804312Z","end":"2026-04-19T11:04:07.927212Z","steps":["trace[1113068083] 'process raft request' (duration: 122.740668ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:07.927553Z","caller":"traceutil/trace.go:171","msg":"trace[667069806] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"122.922772ms","start":"2026-04-19T11:04:07.804611Z","end":"2026-04-19T11:04:07.927533Z","steps":["trace[667069806] 'process raft request' (duration: 122.562093ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:07.92811Z","caller":"traceutil/trace.go:171","msg":"trace[29032873] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"123.435796ms","start":"2026-04-19T11:04:07.804656Z","end":"2026-04-19T11:04:07.928091Z","steps":["trace[29032873] 'process raft request' (duration: 123.347473ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:09.36302Z","caller":"traceutil/trace.go:171","msg":"trace[112328077] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"164.483677ms","start":"2026-04-19T11:04:09.19851Z","end":"2026-04-19T11:04:09.362994Z","steps":["trace[112328077] 'process raft request' (duration: 114.201835ms)","trace[112328077] 'compare' (duration: 50.126748ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:09.363487Z","caller":"traceutil/trace.go:171","msg":"trace[684912818] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"138.472018ms","start":"2026-04-19T11:04:09.224991Z","end":"2026-04-19T11:04:09.363463Z","steps":["trace[684912818] 'process raft request' (duration: 138.342785ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:10.049211Z","caller":"traceutil/trace.go:171","msg":"trace[1781607678] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"404.538886ms","start":"2026-04-19T11:04:09.644649Z","end":"2026-04-19T11:04:10.049188Z","steps":["trace[1781607678] 'process raft request' (duration: 404.363132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:10.049372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:09.644605Z","time spent":"404.68658ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:10.062339Z","caller":"traceutil/trace.go:171","msg":"trace[708780522] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"160.320688ms","start":"2026-04-19T11:04:09.901996Z","end":"2026-04-19T11:04:10.062317Z","steps":["trace[708780522] 'read index received' (duration: 147.05017ms)","trace[708780522] 'applied index is now lower than readState.Index' (duration: 13.269628ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:10.062378Z","caller":"traceutil/trace.go:171","msg":"trace[772207503] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"237.192963ms","start":"2026-04-19T11:04:09.825135Z","end":"2026-04-19T11:04:10.062328Z","steps":["trace[772207503] 'process raft request' (duration: 236.887976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:10.06262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.585264ms","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-04-19T11:04:10.062656Z","caller":"traceutil/trace.go:171","msg":"trace[89573821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"160.719828ms","start":"2026-04-19T11:04:09.901926Z","end":"2026-04-19T11:04:10.062646Z","steps":["trace[89573821] 'agreement among raft nodes before linearized reading' (duration: 160.517853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:10.062691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.253541ms","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-04-19T11:04:10.06275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.714686ms","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-04-19T11:04:10.062787Z","caller":"traceutil/trace.go:171","msg":"trace[540958538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:706; }","duration":"131.377303ms","start":"2026-04-19T11:04:09.931387Z","end":"2026-04-19T11:04:10.062764Z","steps":["trace[540958538] 'agreement among raft nodes before linearized reading' (duration: 131.162138ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:10.062833Z","caller":"traceutil/trace.go:171","msg":"trace[73660968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"130.871931ms","start":"2026-04-19T11:04:09.931942Z","end":"2026-04-19T11:04:10.062814Z","steps":["trace[73660968] 'agreement among raft nodes before linearized reading' (duration: 130.616664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:10.06297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.595549ms","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-04-19T11:04:10.06305Z","caller":"traceutil/trace.go:171","msg":"trace[1887048779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"131.743223ms","start":"2026-04-19T11:04:09.931291Z","end":"2026-04-19T11:04:10.063035Z","steps":["trace[1887048779] 'agreement among raft nodes before linearized reading' (duration: 131.254091ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:10.634325Z","caller":"traceutil/trace.go:171","msg":"trace[1670727980] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"103.246872ms","start":"2026-04-19T11:04:10.531055Z","end":"2026-04-19T11:04:10.634302Z","steps":["trace[1670727980] 'process raft request' (duration: 102.974095ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:11.743657Z","caller":"traceutil/trace.go:171","msg":"trace[211374240] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"366.648817ms","start":"2026-04-19T11:04:11.376984Z","end":"2026-04-19T11:04:11.743633Z","steps":["trace[211374240] 'read index received' (duration: 366.234097ms)","trace[211374240] 'applied index is now lower than readState.Index' (duration: 413.7µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:11.743749Z","caller":"traceutil/trace.go:171","msg":"trace[1544568076] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"440.508284ms","start":"2026-04-19T11:04:11.303207Z","end":"2026-04-19T11:04:11.743716Z","steps":["trace[1544568076] 'process raft request' (duration: 440.159985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:11.743905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.844172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-19T11:04:11.744033Z","caller":"traceutil/trace.go:171","msg":"trace[741655057] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:734; }","duration":"367.071908ms","start":"2026-04-19T11:04:11.376936Z","end":"2026-04-19T11:04:11.744008Z","steps":["trace[741655057] 'agreement among raft nodes before linearized reading' (duration: 366.840462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:11.744141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.109167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-04-19T11:04:11.744134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.083952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:11.74413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.376918Z","time spent":"367.198932ms","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-19T11:04:11.744204Z","caller":"traceutil/trace.go:171","msg":"trace[228397510] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:734; }","duration":"122.219849ms","start":"2026-04-19T11:04:11.621966Z","end":"2026-04-19T11:04:11.744186Z","steps":["trace[228397510] 'agreement among raft nodes before linearized reading' (duration: 122.017864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:11.74418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.003798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-04-19T11:04:11.744228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.303179Z","time spent":"440.649967ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:11.744383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.986499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-79zvd\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-04-19T11:04:11.744239Z","caller":"traceutil/trace.go:171","msg":"trace[627995006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"288.245466ms","start":"2026-04-19T11:04:11.45597Z","end":"2026-04-19T11:04:11.744215Z","steps":["trace[627995006] 'agreement among raft nodes before linearized reading' (duration: 288.079182ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:11.744418Z","caller":"traceutil/trace.go:171","msg":"trace[1942939881] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:734; }","duration":"322.177353ms","start":"2026-04-19T11:04:11.422069Z","end":"2026-04-19T11:04:11.744246Z","steps":["trace[1942939881] 'agreement among raft nodes before linearized reading' (duration: 321.827344ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:11.744423Z","caller":"traceutil/trace.go:171","msg":"trace[289166718] range","detail":"{range_begin:/registry/pods/kube-system/cilium-79zvd; range_end:; response_count:1; response_revision:734; }","duration":"216.050351ms","start":"2026-04-19T11:04:11.52836Z","end":"2026-04-19T11:04:11.744411Z","steps":["trace[289166718] 'agreement among raft nodes before linearized reading' (duration: 215.581819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:11.744484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.422049Z","time spent":"322.416229ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-19T11:04:12.323043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233025824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:04:12.375905Z","caller":"traceutil/trace.go:171","msg":"trace[1599786862] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"612.921695ms","start":"2026-04-19T11:04:11.762953Z","end":"2026-04-19T11:04:12.375875Z","steps":["trace[1599786862] 'process raft request' (duration: 612.756271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.376174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.762925Z","time spent":"613.10795ms","remote":"127.0.0.1:39878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:12.627218Z","caller":"traceutil/trace.go:171","msg":"trace[2054115298] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"421.234059ms","start":"2026-04-19T11:04:12.205962Z","end":"2026-04-19T11:04:12.627197Z","steps":["trace[2054115298] 'process raft request' (duration: 420.972763ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:12.627362Z","caller":"traceutil/trace.go:171","msg":"trace[1003573051] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"419.414404ms","start":"2026-04-19T11:04:12.20787Z","end":"2026-04-19T11:04:12.627285Z","steps":["trace[1003573051] 'process raft request' (duration: 419.192498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.627424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.205941Z","time spent":"421.403043ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":614,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:04:12.627626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.207861Z","time spent":"419.617888ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:12.685375Z","caller":"traceutil/trace.go:171","msg":"trace[974059480] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:761; }","duration":"862.739806ms","start":"2026-04-19T11:04:11.822599Z","end":"2026-04-19T11:04:12.685339Z","steps":["trace[974059480] 'read index received' (duration: 553.00482ms)","trace[974059480] 'applied index is now lower than readState.Index' (duration: 309.728865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:12.685649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.160348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:12.685695Z","caller":"traceutil/trace.go:171","msg":"trace[543004778] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:738; }","duration":"856.23714ms","start":"2026-04-19T11:04:11.829445Z","end":"2026-04-19T11:04:12.685682Z","steps":["trace[543004778] 'agreement among raft nodes before linearized reading' (duration: 856.123507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.788001ms","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-04-19T11:04:12.685771Z","caller":"traceutil/trace.go:171","msg":"trace[1072250060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"604.878504ms","start":"2026-04-19T11:04:12.08088Z","end":"2026-04-19T11:04:12.685759Z","steps":["trace[1072250060] 'agreement among raft nodes before linearized reading' (duration: 604.737981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.112635ms","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":"warn","ts":"2026-04-19T11:04:12.68593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.073239ms","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-04-19T11:04:12.68597Z","caller":"traceutil/trace.go:171","msg":"trace[671681642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"605.13829ms","start":"2026-04-19T11:04:12.080824Z","end":"2026-04-19T11:04:12.685962Z","steps":["trace[671681642] 'agreement among raft nodes before linearized reading' (duration: 604.934765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.021078ms","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-04-19T11:04:12.685995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.08081Z","time spent":"605.179382ms","remote":"127.0.0.1:39976","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-04-19T11:04:12.686011Z","caller":"traceutil/trace.go:171","msg":"trace[1085737927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"605.070529ms","start":"2026-04-19T11:04:12.080934Z","end":"2026-04-19T11:04:12.686005Z","steps":["trace[1085737927] 'agreement among raft nodes before linearized reading' (duration: 605.021508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.266185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-04-19T11:04:12.686027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.080911Z","time spent":"605.11266ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T11:04:12.686052Z","caller":"traceutil/trace.go:171","msg":"trace[2031931468] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"264.414739ms","start":"2026-04-19T11:04:12.421621Z","end":"2026-04-19T11:04:12.686036Z","steps":["trace[2031931468] 'agreement among raft nodes before linearized reading' (duration: 264.107401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.686179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.203389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:12.686215Z","caller":"traceutil/trace.go:171","msg":"trace[1326408886] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:738; }","duration":"856.25146ms","start":"2026-04-19T11:04:11.829957Z","end":"2026-04-19T11:04:12.686208Z","steps":["trace[1326408886] 'agreement among raft nodes before linearized reading' (duration: 856.200689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.080871Z","time spent":"604.949846ms","remote":"127.0.0.1:39976","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-04-19T11:04:12.686233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.829951Z","time spent":"856.277262ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"info","ts":"2026-04-19T11:04:12.685986Z","caller":"traceutil/trace.go:171","msg":"trace[1122066078] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"863.385882ms","start":"2026-04-19T11:04:11.822565Z","end":"2026-04-19T11:04:12.685951Z","steps":["trace[1122066078] 'agreement among raft nodes before linearized reading' (duration: 862.871789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.68635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.822551Z","time spent":"863.786581ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-19T11:04:12.686372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.834955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:12.686406Z","caller":"traceutil/trace.go:171","msg":"trace[1768622997] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:738; }","duration":"856.905537ms","start":"2026-04-19T11:04:11.829492Z","end":"2026-04-19T11:04:12.686397Z","steps":["trace[1768622997] 'agreement among raft nodes before linearized reading' (duration: 856.844585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.685735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.829383Z","time spent":"856.340363ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:04:12.686432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:11.829471Z","time spent":"856.953267ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-04-19T11:04:12.686505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.939806ms","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-04-19T11:04:12.685942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.071853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-19T11:04:12.686539Z","caller":"traceutil/trace.go:171","msg":"trace[1134487557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"611.006337ms","start":"2026-04-19T11:04:12.075526Z","end":"2026-04-19T11:04:12.686532Z","steps":["trace[1134487557] 'agreement among raft nodes before linearized reading' (duration: 610.927955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.686559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.075507Z","time spent":"611.046119ms","remote":"127.0.0.1:39976","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-04-19T11:04:12.686565Z","caller":"traceutil/trace.go:171","msg":"trace[378177817] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"464.73215ms","start":"2026-04-19T11:04:12.221818Z","end":"2026-04-19T11:04:12.686551Z","steps":["trace[378177817] 'agreement among raft nodes before linearized reading' (duration: 464.079783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.686582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.7893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-04-19T11:04:12.686599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.221802Z","time spent":"464.786771ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-04-19T11:04:12.686626Z","caller":"traceutil/trace.go:171","msg":"trace[371007435] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"663.855053ms","start":"2026-04-19T11:04:12.022755Z","end":"2026-04-19T11:04:12.68661Z","steps":["trace[371007435] 'agreement among raft nodes before linearized reading' (duration: 663.737109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:12.68695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:12.022735Z","time spent":"663.916554ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-04-19T11:04:13.116167Z","caller":"traceutil/trace.go:171","msg":"trace[1631239170] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"177.352912ms","start":"2026-04-19T11:04:12.938783Z","end":"2026-04-19T11:04:13.116136Z","steps":["trace[1631239170] 'process raft request' (duration: 146.601097ms)","trace[1631239170] 'compare' (duration: 30.55409ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:13.310828Z","caller":"traceutil/trace.go:171","msg":"trace[1877871124] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"108.964399ms","start":"2026-04-19T11:04:13.20184Z","end":"2026-04-19T11:04:13.310804Z","steps":["trace[1877871124] 'process raft request' (duration: 31.33954ms)","trace[1877871124] 'compare' (duration: 77.390123ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:14.804615Z","caller":"traceutil/trace.go:171","msg":"trace[213220175] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"134.58961ms","start":"2026-04-19T11:04:14.669996Z","end":"2026-04-19T11:04:14.804585Z","steps":["trace[213220175] 'process raft request' (duration: 134.467527ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:14.973476Z","caller":"traceutil/trace.go:171","msg":"trace[40380510] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"165.508742ms","start":"2026-04-19T11:04:14.807949Z","end":"2026-04-19T11:04:14.973458Z","steps":["trace[40380510] 'process raft request' (duration: 165.44884ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:14.973509Z","caller":"traceutil/trace.go:171","msg":"trace[1440129952] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"165.546803ms","start":"2026-04-19T11:04:14.807925Z","end":"2026-04-19T11:04:14.973471Z","steps":["trace[1440129952] 'process raft request' (duration: 140.904645ms)","trace[1440129952] 'compare' (duration: 24.419773ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:14.973797Z","caller":"traceutil/trace.go:171","msg":"trace[2021980067] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"164.865767ms","start":"2026-04-19T11:04:14.808919Z","end":"2026-04-19T11:04:14.973785Z","steps":["trace[2021980067] 'process raft request' (duration: 164.505228ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:14.974072Z","caller":"traceutil/trace.go:171","msg":"trace[390813582] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"120.796939ms","start":"2026-04-19T11:04:14.853257Z","end":"2026-04-19T11:04:14.974054Z","steps":["trace[390813582] 'process raft request' (duration: 120.658146ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:14.974197Z","caller":"traceutil/trace.go:171","msg":"trace[2127414282] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"165.594144ms","start":"2026-04-19T11:04:14.807927Z","end":"2026-04-19T11:04:14.973521Z","steps":["trace[2127414282] 'process raft request' (duration: 165.43553ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:22.643974Z","caller":"traceutil/trace.go:171","msg":"trace[974724650] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"134.113167ms","start":"2026-04-19T11:04:22.509828Z","end":"2026-04-19T11:04:22.643941Z","steps":["trace[974724650] 'process raft request' (duration: 133.899692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:22.970379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.983613ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-19T11:04:23.955444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233026002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:04:24.255358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.597801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:04:24.255492Z","caller":"traceutil/trace.go:171","msg":"trace[1059037573] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.194973338s","start":"2026-04-19T11:04:23.060493Z","end":"2026-04-19T11:04:24.255466Z","steps":["trace[1059037573] 'process raft request' (duration: 243.152461ms)","trace[1059037573] 'compare' (duration: 950.416245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:24.255709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.060471Z","time spent":"1.195192433s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:24.45666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055705692233026002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:04:24.465342Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161732309s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:04:24.47545Z","caller":"traceutil/trace.go:171","msg":"trace[115464718] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"1.414535s","start":"2026-04-19T11:04:23.060888Z","end":"2026-04-19T11:04:24.475423Z","steps":["trace[115464718] 'process raft request' (duration: 1.404687195s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.475613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.060878Z","time spent":"1.414649082s","remote":"127.0.0.1:39976","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-04-19T11:04:24.475654Z","caller":"traceutil/trace.go:171","msg":"trace[1756154700] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.41408197s","start":"2026-04-19T11:04:23.061543Z","end":"2026-04-19T11:04:24.475625Z","steps":["trace[1756154700] 'process raft request' (duration: 1.413710351s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.475819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.061533Z","time spent":"1.414197122s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:24.475854Z","caller":"traceutil/trace.go:171","msg":"trace[387892135] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.413928006s","start":"2026-04-19T11:04:23.061893Z","end":"2026-04-19T11:04:24.475821Z","steps":["trace[387892135] 'process raft request' (duration: 1.41364147s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.47608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.061876Z","time spent":"1.41406077s","remote":"127.0.0.1:39976","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-04-19T11:04:24.486336Z","caller":"traceutil/trace.go:171","msg":"trace[2065255312] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"1.135326988s","start":"2026-04-19T11:04:23.350993Z","end":"2026-04-19T11:04:24.48632Z","steps":["trace[2065255312] 'process raft request' (duration: 1.135205855s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.486436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.35097Z","time spent":"1.135414411s","remote":"127.0.0.1:39976","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-04-19T11:04:24.487367Z","caller":"traceutil/trace.go:171","msg":"trace[1167486892] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"757.520898ms","start":"2026-04-19T11:04:23.729829Z","end":"2026-04-19T11:04:24.48735Z","steps":["trace[1167486892] 'process raft request' (duration: 756.451474ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.487348Z","caller":"traceutil/trace.go:171","msg":"trace[851335824] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:844; }","duration":"1.032570955s","start":"2026-04-19T11:04:23.4547Z","end":"2026-04-19T11:04:24.487271Z","steps":["trace[851335824] 'read index received' (duration: 1.010892019s)","trace[851335824] 'applied index is now lower than readState.Index' (duration: 21.677576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:24.487447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.729805Z","time spent":"757.60119ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:24.487539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015914072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.487555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.143475ms","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":"info","ts":"2026-04-19T11:04:24.487582Z","caller":"traceutil/trace.go:171","msg":"trace[1245193717] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"1.015996643s","start":"2026-04-19T11:04:23.471571Z","end":"2026-04-19T11:04:24.487568Z","steps":["trace[1245193717] 'agreement among raft nodes before linearized reading' (duration: 1.015897851s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.487592Z","caller":"traceutil/trace.go:171","msg":"trace[1348294627] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"982.210736ms","start":"2026-04-19T11:04:23.505368Z","end":"2026-04-19T11:04:24.487579Z","steps":["trace[1348294627] 'agreement among raft nodes before linearized reading' (duration: 982.148055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.487622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.505344Z","time spent":"982.268657ms","remote":"127.0.0.1:50220","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-04-19T11:04:24.487663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.913441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.487663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.83493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.487703Z","caller":"traceutil/trace.go:171","msg":"trace[1225648069] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"992.967183ms","start":"2026-04-19T11:04:23.494727Z","end":"2026-04-19T11:04:24.487694Z","steps":["trace[1225648069] 'agreement among raft nodes before linearized reading' (duration: 992.914152ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.487728Z","caller":"traceutil/trace.go:171","msg":"trace[610595097] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"992.907321ms","start":"2026-04-19T11:04:23.494802Z","end":"2026-04-19T11:04:24.48771Z","steps":["trace[610595097] 'agreement among raft nodes before linearized reading' (duration: 992.799759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.487737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.494713Z","time spent":"993.012034ms","remote":"127.0.0.1:50212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-04-19T11:04:24.48776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016055935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.487792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.494786Z","time spent":"992.989773ms","remote":"127.0.0.1:50212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:04:24.487822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.636777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.487835Z","caller":"traceutil/trace.go:171","msg":"trace[873316765] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"1.016167137s","start":"2026-04-19T11:04:23.471651Z","end":"2026-04-19T11:04:24.487819Z","steps":["trace[873316765] 'agreement among raft nodes before linearized reading' (duration: 1.016010423s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.487858Z","caller":"traceutil/trace.go:171","msg":"trace[1415382570] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"969.672037ms","start":"2026-04-19T11:04:23.518173Z","end":"2026-04-19T11:04:24.487845Z","steps":["trace[1415382570] 'agreement among raft nodes before linearized reading' (duration: 969.619856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.487878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.471645Z","time spent":"1.016224238s","remote":"127.0.0.1:50210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-04-19T11:04:24.487887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.518167Z","time spent":"969.710868ms","remote":"127.0.0.1:50232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-19T11:04:24.48794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.605315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.487958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.061842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.487974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032858641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.487998Z","caller":"traceutil/trace.go:171","msg":"trace[780461764] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"982.681767ms","start":"2026-04-19T11:04:23.505301Z","end":"2026-04-19T11:04:24.487983Z","steps":["trace[780461764] 'agreement among raft nodes before linearized reading' (duration: 982.596445ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.488024Z","caller":"traceutil/trace.go:171","msg":"trace[382637694] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"982.148284ms","start":"2026-04-19T11:04:23.505855Z","end":"2026-04-19T11:04:24.488003Z","steps":["trace[382637694] 'agreement among raft nodes before linearized reading' (duration: 982.057772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.488134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016494615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:24.48762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.471557Z","time spent":"1.016053334s","remote":"127.0.0.1:50210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:04:24.488161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.505841Z","time spent":"982.300987ms","remote":"127.0.0.1:50220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-04-19T11:04:24.488162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.222358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.488187Z","caller":"traceutil/trace.go:171","msg":"trace[1355650081] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"1.016595747s","start":"2026-04-19T11:04:23.47158Z","end":"2026-04-19T11:04:24.488176Z","steps":["trace[1355650081] 'agreement among raft nodes before linearized reading' (duration: 1.016502915s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.488233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.471557Z","time spent":"1.016664818s","remote":"127.0.0.1:50210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"info","ts":"2026-04-19T11:04:24.488249Z","caller":"traceutil/trace.go:171","msg":"trace[2102637728] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"993.286179ms","start":"2026-04-19T11:04:23.494914Z","end":"2026-04-19T11:04:24.4882Z","steps":["trace[2102637728] 'agreement among raft nodes before linearized reading' (duration: 993.215138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.488278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.21227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.488303Z","caller":"traceutil/trace.go:171","msg":"trace[478064491] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"970.23409ms","start":"2026-04-19T11:04:23.51806Z","end":"2026-04-19T11:04:24.488294Z","steps":["trace[478064491] 'agreement among raft nodes before linearized reading' (duration: 970.20426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.488101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.50529Z","time spent":"982.787609ms","remote":"127.0.0.1:50220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-04-19T11:04:24.488305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.494906Z","time spent":"993.383742ms","remote":"127.0.0.1:50212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-04-19T11:04:24.488324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.518042Z","time spent":"970.276131ms","remote":"127.0.0.1:50232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-19T11:04:24.488248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.199079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:24.48814Z","caller":"traceutil/trace.go:171","msg":"trace[1716040946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"1.033361142s","start":"2026-04-19T11:04:23.454658Z","end":"2026-04-19T11:04:24.48802Z","steps":["trace[1716040946] 'agreement among raft nodes before linearized reading' (duration: 1.032782329s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:24.488423Z","caller":"traceutil/trace.go:171","msg":"trace[343612133] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"970.388574ms","start":"2026-04-19T11:04:23.518019Z","end":"2026-04-19T11:04:24.488408Z","steps":["trace[343612133] 'agreement among raft nodes before linearized reading' (duration: 970.20066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:24.488449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.454639Z","time spent":"1.033803812s","remote":"127.0.0.1:54862","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-04-19T11:04:24.488473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:23.518005Z","time spent":"970.453515ms","remote":"127.0.0.1:50232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-19T11:04:26.399626Z","caller":"traceutil/trace.go:171","msg":"trace[885925950] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"103.367356ms","start":"2026-04-19T11:04:26.296235Z","end":"2026-04-19T11:04:26.399602Z","steps":["trace[885925950] 'process raft request' (duration: 103.235783ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:31.404961Z","caller":"traceutil/trace.go:171","msg":"trace[157893409] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"122.015868ms","start":"2026-04-19T11:04:31.282921Z","end":"2026-04-19T11:04:31.404937Z","steps":["trace[157893409] 'process raft request' (duration: 121.684221ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:31.405155Z","caller":"traceutil/trace.go:171","msg":"trace[978682308] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"118.639565ms","start":"2026-04-19T11:04:31.286491Z","end":"2026-04-19T11:04:31.40513Z","steps":["trace[978682308] 'process raft request' (duration: 118.331728ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:32.527143Z","caller":"traceutil/trace.go:171","msg":"trace[1217871793] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"144.264369ms","start":"2026-04-19T11:04:32.382847Z","end":"2026-04-19T11:04:32.527112Z","steps":["trace[1217871793] 'read index received' (duration: 103.274266ms)","trace[1217871793] 'applied index is now lower than readState.Index' (duration: 40.987993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:32.527416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.562586ms","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-04-19T11:04:32.527483Z","caller":"traceutil/trace.go:171","msg":"trace[1898364735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:893; }","duration":"144.693059ms","start":"2026-04-19T11:04:32.382775Z","end":"2026-04-19T11:04:32.527468Z","steps":["trace[1898364735] 'agreement among raft nodes before linearized reading' (duration: 144.444484ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:32.912603Z","caller":"traceutil/trace.go:171","msg":"trace[1357123119] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"302.771956ms","start":"2026-04-19T11:04:32.609807Z","end":"2026-04-19T11:04:32.912578Z","steps":["trace[1357123119] 'process raft request' (duration: 302.623432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:32.912774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:32.609778Z","time spent":"302.910289ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:32.924326Z","caller":"traceutil/trace.go:171","msg":"trace[411675810] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"313.870314ms","start":"2026-04-19T11:04:32.610432Z","end":"2026-04-19T11:04:32.924302Z","steps":["trace[411675810] 'process raft request' (duration: 313.717211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:32.924582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:32.610393Z","time spent":"314.1251ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:33.225656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.788575ms","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-04-19T11:04:33.225806Z","caller":"traceutil/trace.go:171","msg":"trace[2112309274] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:897; }","duration":"170.98557ms","start":"2026-04-19T11:04:33.054801Z","end":"2026-04-19T11:04:33.225786Z","steps":["trace[2112309274] 'count revisions from in-memory index tree' (duration: 170.673653ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:33.225851Z","caller":"traceutil/trace.go:171","msg":"trace[486905973] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"116.981203ms","start":"2026-04-19T11:04:33.108854Z","end":"2026-04-19T11:04:33.225835Z","steps":["trace[486905973] 'process raft request' (duration: 116.673046ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:34.83604Z","caller":"traceutil/trace.go:171","msg":"trace[1655263273] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"169.018832ms","start":"2026-04-19T11:04:34.666978Z","end":"2026-04-19T11:04:34.835997Z","steps":["trace[1655263273] 'process raft request' (duration: 167.01315ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:34.856117Z","caller":"traceutil/trace.go:171","msg":"trace[1786646868] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"189.042567ms","start":"2026-04-19T11:04:34.667048Z","end":"2026-04-19T11:04:34.856091Z","steps":["trace[1786646868] 'process raft request' (duration: 188.804352ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:34.856242Z","caller":"traceutil/trace.go:171","msg":"trace[917257723] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"176.634844ms","start":"2026-04-19T11:04:34.679588Z","end":"2026-04-19T11:04:34.856223Z","steps":["trace[917257723] 'process raft request' (duration: 176.43236ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:35.859354Z","caller":"traceutil/trace.go:171","msg":"trace[318612388] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"106.010327ms","start":"2026-04-19T11:04:35.753288Z","end":"2026-04-19T11:04:35.859298Z","steps":["trace[318612388] 'process raft request' (duration: 93.651246ms)","trace[318612388] 'compare' (duration: 12.231119ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:36.089608Z","caller":"traceutil/trace.go:171","msg":"trace[625601256] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"164.828778ms","start":"2026-04-19T11:04:35.92475Z","end":"2026-04-19T11:04:36.089579Z","steps":["trace[625601256] 'process raft request' (duration: 137.375812ms)","trace[625601256] 'compare' (duration: 27.318683ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:36.360699Z","caller":"traceutil/trace.go:171","msg":"trace[1011310689] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"149.463524ms","start":"2026-04-19T11:04:36.211206Z","end":"2026-04-19T11:04:36.360669Z","steps":["trace[1011310689] 'process raft request' (duration: 94.833679ms)","trace[1011310689] 'compare' (duration: 54.484082ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:36.401335Z","caller":"traceutil/trace.go:171","msg":"trace[1234675135] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"134.513531ms","start":"2026-04-19T11:04:36.266792Z","end":"2026-04-19T11:04:36.401305Z","steps":["trace[1234675135] 'process raft request' (duration: 134.327497ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:37.351843Z","caller":"traceutil/trace.go:171","msg":"trace[128778069] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"106.073029ms","start":"2026-04-19T11:04:37.245742Z","end":"2026-04-19T11:04:37.351815Z","steps":["trace[128778069] 'process raft request' (duration: 105.769063ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:38.541992Z","caller":"traceutil/trace.go:171","msg":"trace[539219166] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"136.042515ms","start":"2026-04-19T11:04:38.405909Z","end":"2026-04-19T11:04:38.541951Z","steps":["trace[539219166] 'process raft request' (duration: 135.836781ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:38.542296Z","caller":"traceutil/trace.go:171","msg":"trace[303243072] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"130.619473ms","start":"2026-04-19T11:04:38.411656Z","end":"2026-04-19T11:04:38.542275Z","steps":["trace[303243072] 'process raft request' (duration: 130.232465ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:39.67715Z","caller":"traceutil/trace.go:171","msg":"trace[1723684655] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"286.784356ms","start":"2026-04-19T11:04:39.390337Z","end":"2026-04-19T11:04:39.677121Z","steps":["trace[1723684655] 'read index received' (duration: 286.666963ms)","trace[1723684655] 'applied index is now lower than readState.Index' (duration: 116.213µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:39.67727Z","caller":"traceutil/trace.go:171","msg":"trace[1589773893] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"320.899886ms","start":"2026-04-19T11:04:39.356348Z","end":"2026-04-19T11:04:39.677248Z","steps":["trace[1589773893] 'process raft request' (duration: 320.60885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:39.677374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.01248ms","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-04-19T11:04:39.677438Z","caller":"traceutil/trace.go:171","msg":"trace[1867867226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"287.119613ms","start":"2026-04-19T11:04:39.390302Z","end":"2026-04-19T11:04:39.677422Z","steps":["trace[1867867226] 'agreement among raft nodes before linearized reading' (duration: 286.926869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:39.677441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:39.356325Z","time spent":"321.015329ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:04:40.527296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.770057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:04:40.527423Z","caller":"traceutil/trace.go:171","msg":"trace[181908278] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"336.775359ms","start":"2026-04-19T11:04:40.190631Z","end":"2026-04-19T11:04:40.527406Z","steps":["trace[181908278] 'read index received' (duration: 88.242µs)","trace[181908278] 'applied index is now lower than readState.Index' (duration: 336.685737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:40.527599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.930372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:04:40.527676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.730299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:40.527681Z","caller":"traceutil/trace.go:171","msg":"trace[203125656] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:980; }","duration":"337.076475ms","start":"2026-04-19T11:04:40.190583Z","end":"2026-04-19T11:04:40.527659Z","steps":["trace[203125656] 'agreement among raft nodes before linearized reading' (duration: 336.915012ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:40.527712Z","caller":"traceutil/trace.go:171","msg":"trace[797671787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"175.802151ms","start":"2026-04-19T11:04:40.351897Z","end":"2026-04-19T11:04:40.527699Z","steps":["trace[797671787] 'agreement among raft nodes before linearized reading' (duration: 175.74567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:40.527724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:40.190561Z","time spent":"337.152796ms","remote":"127.0.0.1:39916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-19T11:04:40.527993Z","caller":"traceutil/trace.go:171","msg":"trace[499943266] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"844.787288ms","start":"2026-04-19T11:04:39.683161Z","end":"2026-04-19T11:04:40.527948Z","steps":["trace[499943266] 'process raft request' (duration: 372.281676ms)","trace[499943266] 'compare' (duration: 471.585614ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:40.52862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:04:39.683132Z","time spent":"845.38553ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:04:44.691984Z","caller":"traceutil/trace.go:171","msg":"trace[1376247982] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"145.805124ms","start":"2026-04-19T11:04:44.546161Z","end":"2026-04-19T11:04:44.691966Z","steps":["trace[1376247982] 'process raft request' (duration: 145.688021ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:45.012238Z","caller":"traceutil/trace.go:171","msg":"trace[2093688088] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"264.945145ms","start":"2026-04-19T11:04:44.747212Z","end":"2026-04-19T11:04:45.012157Z","steps":["trace[2093688088] 'process raft request' (duration: 218.466391ms)","trace[2093688088] 'compare' (duration: 45.944424ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:45.256271Z","caller":"traceutil/trace.go:171","msg":"trace[1038868747] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"240.544651ms","start":"2026-04-19T11:04:45.015697Z","end":"2026-04-19T11:04:45.256242Z","steps":["trace[1038868747] 'process raft request' (duration: 166.289591ms)","trace[1038868747] 'compare' (duration: 73.963224ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:45.256317Z","caller":"traceutil/trace.go:171","msg":"trace[1583449452] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"204.460187ms","start":"2026-04-19T11:04:45.051822Z","end":"2026-04-19T11:04:45.256283Z","steps":["trace[1583449452] 'read index received' (duration: 130.185216ms)","trace[1583449452] 'applied index is now lower than readState.Index' (duration: 74.272241ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:45.256344Z","caller":"traceutil/trace.go:171","msg":"trace[1906862078] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"174.375421ms","start":"2026-04-19T11:04:45.08192Z","end":"2026-04-19T11:04:45.256296Z","steps":["trace[1906862078] 'process raft request' (duration: 174.280099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:45.256592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.748982ms","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-04-19T11:04:45.256635Z","caller":"traceutil/trace.go:171","msg":"trace[1601194177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"204.847524ms","start":"2026-04-19T11:04:45.051776Z","end":"2026-04-19T11:04:45.256623Z","steps":["trace[1601194177] 'agreement among raft nodes before linearized reading' (duration: 204.65225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:45.256677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.734859ms","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-04-19T11:04:45.256734Z","caller":"traceutil/trace.go:171","msg":"trace[193277409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"170.822311ms","start":"2026-04-19T11:04:45.085898Z","end":"2026-04-19T11:04:45.25672Z","steps":["trace[193277409] 'agreement among raft nodes before linearized reading' (duration: 170.645568ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:45.415828Z","caller":"traceutil/trace.go:171","msg":"trace[282686371] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"154.447137ms","start":"2026-04-19T11:04:45.261354Z","end":"2026-04-19T11:04:45.415801Z","steps":["trace[282686371] 'process raft request' (duration: 111.715851ms)","trace[282686371] 'compare' (duration: 42.615043ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:47.383485Z","caller":"traceutil/trace.go:171","msg":"trace[1742178768] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"102.156226ms","start":"2026-04-19T11:04:47.281305Z","end":"2026-04-19T11:04:47.383461Z","steps":["trace[1742178768] 'read index received' (duration: 101.913682ms)","trace[1742178768] 'applied index is now lower than readState.Index' (duration: 241.644µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:47.383599Z","caller":"traceutil/trace.go:171","msg":"trace[1166623036] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"119.580557ms","start":"2026-04-19T11:04:47.263979Z","end":"2026-04-19T11:04:47.383559Z","steps":["trace[1166623036] 'process raft request' (duration: 119.316182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:47.38369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.373111ms","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-04-19T11:04:47.383785Z","caller":"traceutil/trace.go:171","msg":"trace[1087353387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"102.520583ms","start":"2026-04-19T11:04:47.281253Z","end":"2026-04-19T11:04:47.383774Z","steps":["trace[1087353387] 'agreement among raft nodes before linearized reading' (duration: 102.300039ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:02.938106Z","caller":"traceutil/trace.go:171","msg":"trace[2000428011] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"103.575811ms","start":"2026-04-19T11:05:02.834512Z","end":"2026-04-19T11:05:02.938087Z","steps":["trace[2000428011] 'process raft request' (duration: 103.441369ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:15.68367Z","caller":"traceutil/trace.go:171","msg":"trace[1034865615] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"123.188148ms","start":"2026-04-19T11:05:15.560452Z","end":"2026-04-19T11:05:15.68364Z","steps":["trace[1034865615] 'process raft request' (duration: 123.012745ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:20.097305Z","caller":"traceutil/trace.go:171","msg":"trace[434647209] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"173.445409ms","start":"2026-04-19T11:05:19.923841Z","end":"2026-04-19T11:05:20.097287Z","steps":["trace[434647209] 'process raft request' (duration: 173.285907ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:20.128683Z","caller":"traceutil/trace.go:171","msg":"trace[1474706426] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"183.694196ms","start":"2026-04-19T11:05:19.94494Z","end":"2026-04-19T11:05:20.128634Z","steps":["trace[1474706426] 'process raft request' (duration: 183.374701ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:34.68409Z","caller":"traceutil/trace.go:171","msg":"trace[1255633366] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"105.399448ms","start":"2026-04-19T11:05:34.578663Z","end":"2026-04-19T11:05:34.684062Z","steps":["trace[1255633366] 'process raft request' (duration: 105.257435ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:52.076572Z","caller":"traceutil/trace.go:171","msg":"trace[830167445] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"160.228577ms","start":"2026-04-19T11:05:51.916305Z","end":"2026-04-19T11:05:52.076534Z","steps":["trace[830167445] 'process raft request' (duration: 160.056284ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:52.197346Z","caller":"traceutil/trace.go:171","msg":"trace[1518949706] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"142.567242ms","start":"2026-04-19T11:05:52.054716Z","end":"2026-04-19T11:05:52.197284Z","steps":["trace[1518949706] 'read index received' (duration: 21.598809ms)","trace[1518949706] 'applied index is now lower than readState.Index' (duration: 120.965833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:05:52.197586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.860126ms","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-04-19T11:05:52.197624Z","caller":"traceutil/trace.go:171","msg":"trace[265892154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"142.917606ms","start":"2026-04-19T11:05:52.054696Z","end":"2026-04-19T11:05:52.197614Z","steps":["trace[265892154] 'agreement among raft nodes before linearized reading' (duration: 142.751104ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:55.998184Z","caller":"traceutil/trace.go:171","msg":"trace[2076962488] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"339.92472ms","start":"2026-04-19T11:05:55.658238Z","end":"2026-04-19T11:05:55.998162Z","steps":["trace[2076962488] 'process raft request' (duration: 339.811528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:05:55.998324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:05:55.658218Z","time spent":"340.036992ms","remote":"127.0.0.1:39976","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-04-19T11:05:56.473738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.588415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-19T11:05:56.473862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.153667ms","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-04-19T11:05:56.473963Z","caller":"traceutil/trace.go:171","msg":"trace[1885503589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"106.32323ms","start":"2026-04-19T11:05:56.367616Z","end":"2026-04-19T11:05:56.473939Z","steps":["trace[1885503589] 'range keys from in-memory index tree' (duration: 105.961635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:05:56.473962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.971499ms","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-04-19T11:05:56.474102Z","caller":"traceutil/trace.go:171","msg":"trace[1223402298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"227.154661ms","start":"2026-04-19T11:05:56.24692Z","end":"2026-04-19T11:05:56.474075Z","steps":["trace[1223402298] 'range keys from in-memory index tree' (duration: 226.722135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:05:56.474026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.764515ms","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-04-19T11:05:56.474182Z","caller":"traceutil/trace.go:171","msg":"trace[1967107969] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1239; }","duration":"143.923297ms","start":"2026-04-19T11:05:56.330239Z","end":"2026-04-19T11:05:56.474162Z","steps":["trace[1967107969] 'range keys from in-memory index tree' (duration: 143.750585ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:56.474202Z","caller":"traceutil/trace.go:171","msg":"trace[534313067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"353.855278ms","start":"2026-04-19T11:05:56.120068Z","end":"2026-04-19T11:05:56.473924Z","steps":["trace[534313067] 'range keys from in-memory index tree' (duration: 353.368012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:05:56.474954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:05:56.120048Z","time spent":"354.833474ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T11:05:56.475872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.033157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-04-19T11:05:56.476072Z","caller":"traceutil/trace.go:171","msg":"trace[813122587] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1239; }","duration":"139.16516ms","start":"2026-04-19T11:05:56.336776Z","end":"2026-04-19T11:05:56.475941Z","steps":["trace[813122587] 'range keys from in-memory index tree' (duration: 137.260041ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:56.639553Z","caller":"traceutil/trace.go:171","msg":"trace[1756889781] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"161.085979ms","start":"2026-04-19T11:05:56.478426Z","end":"2026-04-19T11:05:56.639512Z","steps":["trace[1756889781] 'process raft request' (duration: 143.118126ms)","trace[1756889781] 'compare' (duration: 17.667697ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:05:56.639561Z","caller":"traceutil/trace.go:171","msg":"trace[519553040] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"160.680563ms","start":"2026-04-19T11:05:56.478853Z","end":"2026-04-19T11:05:56.639534Z","steps":["trace[519553040] 'process raft request' (duration: 160.57093ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:56.640015Z","caller":"traceutil/trace.go:171","msg":"trace[1974953829] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"160.877476ms","start":"2026-04-19T11:05:56.479131Z","end":"2026-04-19T11:05:56.640009Z","steps":["trace[1974953829] 'process raft request' (duration: 160.360888ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:56.640136Z","caller":"traceutil/trace.go:171","msg":"trace[847180084] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1291; }","duration":"105.369896ms","start":"2026-04-19T11:05:56.534761Z","end":"2026-04-19T11:05:56.640131Z","steps":["trace[847180084] 'read index received' (duration: 86.737844ms)","trace[847180084] 'applied index is now lower than readState.Index' (duration: 18.631662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:05:56.640188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.413777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:05:56.640204Z","caller":"traceutil/trace.go:171","msg":"trace[393521340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"105.467447ms","start":"2026-04-19T11:05:56.534731Z","end":"2026-04-19T11:05:56.640199Z","steps":["trace[393521340] 'agreement among raft nodes before linearized reading' (duration: 105.419877ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:56.640294Z","caller":"traceutil/trace.go:171","msg":"trace[1541029857] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"126.267291ms","start":"2026-04-19T11:05:56.514022Z","end":"2026-04-19T11:05:56.640289Z","steps":["trace[1541029857] 'process raft request' (duration: 125.919016ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:05:59.194815Z","caller":"traceutil/trace.go:171","msg":"trace[879280023] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"206.043961ms","start":"2026-04-19T11:05:58.988745Z","end":"2026-04-19T11:05:59.194789Z","steps":["trace[879280023] 'process raft request' (duration: 205.902629ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:02.862915Z","caller":"traceutil/trace.go:171","msg":"trace[958841210] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"133.950422ms","start":"2026-04-19T11:06:02.728938Z","end":"2026-04-19T11:06:02.862888Z","steps":["trace[958841210] 'read index received' (duration: 133.75275ms)","trace[958841210] 'applied index is now lower than readState.Index' (duration: 196.642µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:02.862989Z","caller":"traceutil/trace.go:171","msg":"trace[932249003] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"170.72058ms","start":"2026-04-19T11:06:02.692232Z","end":"2026-04-19T11:06:02.862952Z","steps":["trace[932249003] 'process raft request' (duration: 170.491227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.863353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.818057ms","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-04-19T11:06:02.863406Z","caller":"traceutil/trace.go:171","msg":"trace[1208687141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1261; }","duration":"123.945398ms","start":"2026-04-19T11:06:02.739449Z","end":"2026-04-19T11:06:02.863395Z","steps":["trace[1208687141] 'agreement among raft nodes before linearized reading' (duration: 123.661514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.863447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.48025ms","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-04-19T11:06:02.863542Z","caller":"traceutil/trace.go:171","msg":"trace[87171491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"134.613021ms","start":"2026-04-19T11:06:02.728907Z","end":"2026-04-19T11:06:02.863519Z","steps":["trace[87171491] 'agreement among raft nodes before linearized reading' (duration: 134.287707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.863587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.460202ms","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-04-19T11:06:02.863698Z","caller":"traceutil/trace.go:171","msg":"trace[898241879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"123.739935ms","start":"2026-04-19T11:06:02.739943Z","end":"2026-04-19T11:06:02.863683Z","steps":["trace[898241879] 'agreement among raft nodes before linearized reading' (duration: 123.244758ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:03.031723Z","caller":"traceutil/trace.go:171","msg":"trace[1537529649] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"164.118093ms","start":"2026-04-19T11:06:02.867576Z","end":"2026-04-19T11:06:03.031694Z","steps":["trace[1537529649] 'process raft request' (duration: 89.271481ms)","trace[1537529649] 'compare' (duration: 74.67302ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:03.031979Z","caller":"traceutil/trace.go:171","msg":"trace[1802956412] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1314; }","duration":"152.659609ms","start":"2026-04-19T11:06:02.879296Z","end":"2026-04-19T11:06:03.031956Z","steps":["trace[1802956412] 'read index received' (duration: 77.622694ms)","trace[1802956412] 'applied index is now lower than readState.Index' (duration: 75.035885ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:03.032102Z","caller":"traceutil/trace.go:171","msg":"trace[1977535107] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"163.914171ms","start":"2026-04-19T11:06:02.868174Z","end":"2026-04-19T11:06:03.032088Z","steps":["trace[1977535107] 'process raft request' (duration: 163.477075ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:03.032238Z","caller":"traceutil/trace.go:171","msg":"trace[845742459] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"163.968133ms","start":"2026-04-19T11:06:02.868258Z","end":"2026-04-19T11:06:03.032227Z","steps":["trace[845742459] 'process raft request' (duration: 163.602797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.032365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.046456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:03.032405Z","caller":"traceutil/trace.go:171","msg":"trace[234711636] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1264; }","duration":"153.127727ms","start":"2026-04-19T11:06:02.879268Z","end":"2026-04-19T11:06:03.032395Z","steps":["trace[234711636] 'agreement among raft nodes before linearized reading' (duration: 153.042266ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:03.366442Z","caller":"traceutil/trace.go:171","msg":"trace[1698641408] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"114.079483ms","start":"2026-04-19T11:06:03.25234Z","end":"2026-04-19T11:06:03.366419Z","steps":["trace[1698641408] 'process raft request' (duration: 113.950501ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:12.296785Z","caller":"traceutil/trace.go:171","msg":"trace[1387472238] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"108.985248ms","start":"2026-04-19T11:06:12.187777Z","end":"2026-04-19T11:06:12.296762Z","steps":["trace[1387472238] 'read index received' (duration: 65.414256ms)","trace[1387472238] 'applied index is now lower than readState.Index' (duration: 43.570072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:12.297016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.221221ms","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-04-19T11:06:12.297055Z","caller":"traceutil/trace.go:171","msg":"trace[1052743968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"109.273511ms","start":"2026-04-19T11:06:12.18777Z","end":"2026-04-19T11:06:12.297044Z","steps":["trace[1052743968] 'agreement among raft nodes before linearized reading' (duration: 109.085939ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:12.297158Z","caller":"traceutil/trace.go:171","msg":"trace[201534215] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"126.773766ms","start":"2026-04-19T11:06:12.170335Z","end":"2026-04-19T11:06:12.297109Z","steps":["trace[201534215] 'process raft request' (duration: 82.995912ms)","trace[201534215] 'compare' (duration: 43.320328ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:12.550702Z","caller":"traceutil/trace.go:171","msg":"trace[715241518] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"248.578743ms","start":"2026-04-19T11:06:12.302101Z","end":"2026-04-19T11:06:12.55068Z","steps":["trace[715241518] 'process raft request' (duration: 216.084039ms)","trace[715241518] 'compare' (duration: 31.752724ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:12.551012Z","caller":"traceutil/trace.go:171","msg":"trace[714118845] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"248.851026ms","start":"2026-04-19T11:06:12.302126Z","end":"2026-04-19T11:06:12.550977Z","steps":["trace[714118845] 'process raft request' (duration: 248.415661ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:13.178465Z","caller":"traceutil/trace.go:171","msg":"trace[1931770896] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"112.271069ms","start":"2026-04-19T11:06:13.066165Z","end":"2026-04-19T11:06:13.178436Z","steps":["trace[1931770896] 'process raft request' (duration: 95.954292ms)","trace[1931770896] 'compare' (duration: 15.76996ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:15.872871Z","caller":"traceutil/trace.go:171","msg":"trace[1873666289] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"175.195978ms","start":"2026-04-19T11:06:15.697653Z","end":"2026-04-19T11:06:15.872849Z","steps":["trace[1873666289] 'read index received' (duration: 174.985025ms)","trace[1873666289] 'applied index is now lower than readState.Index' (duration: 210.073µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:15.872964Z","caller":"traceutil/trace.go:171","msg":"trace[1684986986] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"403.531408ms","start":"2026-04-19T11:06:15.469395Z","end":"2026-04-19T11:06:15.872926Z","steps":["trace[1684986986] 'process raft request' (duration: 403.309235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:15.87301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.348011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:06:15.873053Z","caller":"traceutil/trace.go:171","msg":"trace[1297729565] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1336; }","duration":"175.461562ms","start":"2026-04-19T11:06:15.697583Z","end":"2026-04-19T11:06:15.873045Z","steps":["trace[1297729565] 'agreement among raft nodes before linearized reading' (duration: 175.36224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:15.873141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:15.469376Z","time spent":"403.67356ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:06:17.329496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.436377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:06:17.329804Z","caller":"traceutil/trace.go:171","msg":"trace[2119614897] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"308.66787ms","start":"2026-04-19T11:06:17.021112Z","end":"2026-04-19T11:06:17.32978Z","steps":["trace[2119614897] 'process raft request' (duration: 193.861908ms)","trace[2119614897] 'compare' (duration: 114.281735ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:17.329826Z","caller":"traceutil/trace.go:171","msg":"trace[1915770981] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"296.334798ms","start":"2026-04-19T11:06:17.033467Z","end":"2026-04-19T11:06:17.329802Z","steps":["trace[1915770981] 'process raft request' (duration: 296.153626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:17.329934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:17.02109Z","time spent":"308.777831ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:06:17.525289Z","caller":"traceutil/trace.go:171","msg":"trace[215266433] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"190.915367ms","start":"2026-04-19T11:06:17.334351Z","end":"2026-04-19T11:06:17.525267Z","steps":["trace[215266433] 'process raft request' (duration: 104.254636ms)","trace[215266433] 'compare' (duration: 86.56563ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:17.526488Z","caller":"traceutil/trace.go:171","msg":"trace[757417147] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"191.800011ms","start":"2026-04-19T11:06:17.334667Z","end":"2026-04-19T11:06:17.526467Z","steps":["trace[757417147] 'process raft request' (duration: 191.653368ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:17.526933Z","caller":"traceutil/trace.go:171","msg":"trace[1559768346] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"191.974563ms","start":"2026-04-19T11:06:17.334931Z","end":"2026-04-19T11:06:17.526905Z","steps":["trace[1559768346] 'process raft request' (duration: 191.501316ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:22.876011Z","caller":"traceutil/trace.go:171","msg":"trace[2032960345] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"103.528981ms","start":"2026-04-19T11:06:22.772418Z","end":"2026-04-19T11:06:22.875947Z","steps":["trace[2032960345] 'read index received' (duration: 103.320638ms)","trace[2032960345] 'applied index is now lower than readState.Index' (duration: 207.303µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:22.876139Z","caller":"traceutil/trace.go:171","msg":"trace[1148930961] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"186.830884ms","start":"2026-04-19T11:06:22.68928Z","end":"2026-04-19T11:06:22.876111Z","steps":["trace[1148930961] 'process raft request' (duration: 186.51848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:22.876316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.836255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:06:22.876394Z","caller":"traceutil/trace.go:171","msg":"trace[1564387497] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1359; }","duration":"103.962487ms","start":"2026-04-19T11:06:22.772405Z","end":"2026-04-19T11:06:22.876368Z","steps":["trace[1564387497] 'agreement among raft nodes before linearized reading' (duration: 103.690733ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:26.220851Z","caller":"traceutil/trace.go:171","msg":"trace[1568305440] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"149.477126ms","start":"2026-04-19T11:06:26.071344Z","end":"2026-04-19T11:06:26.220821Z","steps":["trace[1568305440] 'process raft request' (duration: 149.312994ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:29.12296Z","caller":"traceutil/trace.go:171","msg":"trace[1183950132] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"132.617928ms","start":"2026-04-19T11:06:28.990326Z","end":"2026-04-19T11:06:29.122944Z","steps":["trace[1183950132] 'process raft request' (duration: 132.511256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:29.943353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.409536ms","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":"info","ts":"2026-04-19T11:06:29.943464Z","caller":"traceutil/trace.go:171","msg":"trace[529301666] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1387; }","duration":"304.6691ms","start":"2026-04-19T11:06:29.638764Z","end":"2026-04-19T11:06:29.943434Z","steps":["trace[529301666] 'count revisions from in-memory index tree' (duration: 304.296765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:29.943524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:29.638742Z","time spent":"304.762571ms","remote":"127.0.0.1:40144","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-04-19T11:06:29.943785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.773582ms","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-04-19T11:06:29.943849Z","caller":"traceutil/trace.go:171","msg":"trace[1123324504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1387; }","duration":"203.866583ms","start":"2026-04-19T11:06:29.739966Z","end":"2026-04-19T11:06:29.943832Z","steps":["trace[1123324504] 'range keys from in-memory index tree' (duration: 203.63069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:30.356808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.824011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:06:30.356945Z","caller":"traceutil/trace.go:171","msg":"trace[842553397] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1447; }","duration":"408.084162ms","start":"2026-04-19T11:06:29.948849Z","end":"2026-04-19T11:06:30.356933Z","steps":["trace[842553397] 'read index received' (duration: 46.687843ms)","trace[842553397] 'applied index is now lower than readState.Index' (duration: 361.395479ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:30.357009Z","caller":"traceutil/trace.go:171","msg":"trace[1551978727] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"408.342596ms","start":"2026-04-19T11:06:29.948658Z","end":"2026-04-19T11:06:30.357Z","steps":["trace[1551978727] 'process raft request' (duration: 172.255112ms)","trace[1551978727] 'compare' (duration: 235.7224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:30.357052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:29.948639Z","time spent":"408.388817ms","remote":"127.0.0.1:39976","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-04-19T11:06:30.3573Z","caller":"traceutil/trace.go:171","msg":"trace[1213591896] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"408.61066ms","start":"2026-04-19T11:06:29.94867Z","end":"2026-04-19T11:06:30.357281Z","steps":["trace[1213591896] 'process raft request' (duration: 408.222865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:30.357451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:29.94864Z","time spent":"408.713561ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:06:30.357184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.328095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:30.357538Z","caller":"traceutil/trace.go:171","msg":"trace[270264538] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1390; }","duration":"408.717391ms","start":"2026-04-19T11:06:29.948801Z","end":"2026-04-19T11:06:30.357519Z","steps":["trace[270264538] 'agreement among raft nodes before linearized reading' (duration: 408.295216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:30.357587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:29.948789Z","time spent":"408.782402ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-19T11:06:30.357835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.146165ms","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-04-19T11:06:30.358014Z","caller":"traceutil/trace.go:171","msg":"trace[545610303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1390; }","duration":"114.316317ms","start":"2026-04-19T11:06:30.243541Z","end":"2026-04-19T11:06:30.357857Z","steps":["trace[545610303] 'agreement among raft nodes before linearized reading' (duration: 113.591837ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:34.543237Z","caller":"traceutil/trace.go:171","msg":"trace[152157832] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"167.058866ms","start":"2026-04-19T11:06:34.376157Z","end":"2026-04-19T11:06:34.543215Z","steps":["trace[152157832] 'process raft request' (duration: 166.914914ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:34.55911Z","caller":"traceutil/trace.go:171","msg":"trace[123583367] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1466; }","duration":"167.245298ms","start":"2026-04-19T11:06:34.391836Z","end":"2026-04-19T11:06:34.559081Z","steps":["trace[123583367] 'read index received' (duration: 151.249733ms)","trace[123583367] 'applied index is now lower than readState.Index' (duration: 15.993595ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:34.559243Z","caller":"traceutil/trace.go:171","msg":"trace[1017370244] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"180.990313ms","start":"2026-04-19T11:06:34.378236Z","end":"2026-04-19T11:06:34.559226Z","steps":["trace[1017370244] 'process raft request' (duration: 180.710689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:34.559372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.76784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T11:06:34.559413Z","caller":"traceutil/trace.go:171","msg":"trace[541271844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1406; }","duration":"116.85079ms","start":"2026-04-19T11:06:34.442555Z","end":"2026-04-19T11:06:34.559405Z","steps":["trace[541271844] 'agreement among raft nodes before linearized reading' (duration: 116.705819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:34.559719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.878646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-04-19T11:06:34.55975Z","caller":"traceutil/trace.go:171","msg":"trace[1829719878] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1406; }","duration":"167.939707ms","start":"2026-04-19T11:06:34.391803Z","end":"2026-04-19T11:06:34.559743Z","steps":["trace[1829719878] 'agreement among raft nodes before linearized reading' (duration: 167.660233ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:35.740927Z","caller":"traceutil/trace.go:171","msg":"trace[2127678203] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"157.964919ms","start":"2026-04-19T11:06:35.582899Z","end":"2026-04-19T11:06:35.740864Z","steps":["trace[2127678203] 'process raft request' (duration: 157.536673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:36.224022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.796134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-19T11:06:36.224086Z","caller":"traceutil/trace.go:171","msg":"trace[549375218] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1409; }","duration":"412.917276ms","start":"2026-04-19T11:06:35.811153Z","end":"2026-04-19T11:06:36.22407Z","steps":["trace[549375218] 'range keys from in-memory index tree' (duration: 412.571251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:36.224114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:35.811128Z","time spent":"412.979597ms","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-04-19T11:06:36.224096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.26442ms","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-04-19T11:06:36.224174Z","caller":"traceutil/trace.go:171","msg":"trace[288037334] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1409; }","duration":"322.400901ms","start":"2026-04-19T11:06:35.901752Z","end":"2026-04-19T11:06:36.224153Z","steps":["trace[288037334] 'count revisions from in-memory index tree' (duration: 322.153389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:36.224184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.948317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-19T11:06:36.224339Z","caller":"traceutil/trace.go:171","msg":"trace[913192454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1409; }","duration":"176.145959ms","start":"2026-04-19T11:06:36.04817Z","end":"2026-04-19T11:06:36.224316Z","steps":["trace[913192454] 'range keys from in-memory index tree' (duration: 175.776775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:36.224228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:35.901723Z","time spent":"322.493033ms","remote":"127.0.0.1:50528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-19T11:06:58.027556Z","caller":"traceutil/trace.go:171","msg":"trace[1568094181] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"100.179875ms","start":"2026-04-19T11:06:57.927343Z","end":"2026-04-19T11:06:58.027523Z","steps":["trace[1568094181] 'read index received' (duration: 52.699647ms)","trace[1568094181] 'applied index is now lower than readState.Index' (duration: 47.478848ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:58.02767Z","caller":"traceutil/trace.go:171","msg":"trace[622467728] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"103.783282ms","start":"2026-04-19T11:06:57.923863Z","end":"2026-04-19T11:06:58.027646Z","steps":["trace[622467728] 'process raft request' (duration: 56.240853ms)","trace[622467728] 'compare' (duration: 47.295996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:58.027778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.608944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-19T11:06:58.027839Z","caller":"traceutil/trace.go:171","msg":"trace[1918769621] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1484; }","duration":"118.725905ms","start":"2026-04-19T11:06:57.909097Z","end":"2026-04-19T11:06:58.027822Z","steps":["trace[1918769621] 'agreement among raft nodes before linearized reading' (duration: 118.603803ms)"],"step_count":1}