{"level":"warn","ts":"2026-04-13T21:11:46.995255Z","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-13T21:11:46.99542Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.156:2380","--initial-cluster=instance=https://199.19.213.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T21:11:46.995523Z","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-13T21:11:46.995538Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-04-13T21:11:46.995563Z","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-13T21:11:46.99662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"]} {"level":"info","ts":"2026-04-13T21:11:46.996706Z","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.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T21:11:47.00735Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.327304ms"} {"level":"info","ts":"2026-04-13T21:11:47.014481Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"163ef968c92247ab","cluster-id":"59d11c119c278888"} {"level":"info","ts":"2026-04-13T21:11:47.014552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T21:11:47.014577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 0"} {"level":"info","ts":"2026-04-13T21:11:47.014589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 163ef968c92247ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T21:11:47.014597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 1"} {"level":"info","ts":"2026-04-13T21:11:47.01465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"warn","ts":"2026-04-13T21:11:47.021568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T21:11:47.024797Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T21:11:47.026045Z","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-13T21:11:47.02809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"163ef968c92247ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T21:11:47.028276Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"163ef968c92247ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T21:11:47.032556Z","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-13T21:11:47.0328Z","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-13T21:11:47.032818Z","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-13T21:11:47.033562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"info","ts":"2026-04-13T21:11:47.034121Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","added-peer-id":"163ef968c92247ab","added-peer-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-04-13T21:11:47.037046Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-04-13T21:11:47.037073Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-04-13T21:11:47.036865Z","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-13T21:11:47.038574Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"163ef968c92247ab","initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T21:11:47.038612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T21:11:47.815817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T21:11:47.81596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T21:11:47.815992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgPreVoteResp from 163ef968c92247ab at term 1"} {"level":"info","ts":"2026-04-13T21:11:47.816015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became candidate at term 2"} {"level":"info","ts":"2026-04-13T21:11:47.816028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgVoteResp from 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-04-13T21:11:47.816047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became leader at term 2"} {"level":"info","ts":"2026-04-13T21:11:47.816064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 163ef968c92247ab elected leader 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-04-13T21:11:47.817563Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"163ef968c92247ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.156:2379]}","request-path":"/0/members/163ef968c92247ab/attributes","cluster-id":"59d11c119c278888","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T21:11:47.817631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:11:47.818012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:11:47.81823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:11:47.820013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T21:11:47.822011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.156:2379"} {"level":"info","ts":"2026-04-13T21:11:47.823963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T21:11:47.824043Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T21:11:47.844164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:11:47.844625Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:11:47.844797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-13T21:11:52.77639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.731373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-13T21:11:52.776496Z","caller":"traceutil/trace.go:171","msg":"trace[1045362913] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"113.806963ms","start":"2026-04-13T21:11:52.662658Z","end":"2026-04-13T21:11:52.776465Z","steps":["trace[1045362913] 'read index received' (duration: 109.936326ms)","trace[1045362913] 'applied index is now lower than readState.Index' (duration: 3.869177ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:52.776553Z","caller":"traceutil/trace.go:171","msg":"trace[454498094] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:228; }","duration":"116.950139ms","start":"2026-04-13T21:11:52.659573Z","end":"2026-04-13T21:11:52.776524Z","steps":["trace[454498094] 'range keys from in-memory index tree' (duration: 116.6237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:52.776677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.970247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:52.776774Z","caller":"traceutil/trace.go:171","msg":"trace[1458364696] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:228; }","duration":"114.10854ms","start":"2026-04-13T21:11:52.662642Z","end":"2026-04-13T21:11:52.77675Z","steps":["trace[1458364696] 'agreement among raft nodes before linearized reading' (duration: 113.914525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:11.519687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098691485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:12:11.79833Z","caller":"traceutil/trace.go:171","msg":"trace[838088053] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"779.129586ms","start":"2026-04-13T21:12:11.019173Z","end":"2026-04-13T21:12:11.798303Z","steps":["trace[838088053] 'read index received' (duration: 779.023223ms)","trace[838088053] 'applied index is now lower than readState.Index' (duration: 104.733µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:11.798364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:10.986606Z","time spent":"811.753246ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T21:12:11.798554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.20858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:12:11.798605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.002741ms","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-13T21:12:11.79863Z","caller":"traceutil/trace.go:171","msg":"trace[604024905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"338.352654ms","start":"2026-04-13T21:12:11.46026Z","end":"2026-04-13T21:12:11.798613Z","steps":["trace[604024905] 'agreement among raft nodes before linearized reading' (duration: 338.226839ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:11.798653Z","caller":"traceutil/trace.go:171","msg":"trace[2142042820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"171.098345ms","start":"2026-04-13T21:12:11.62754Z","end":"2026-04-13T21:12:11.798638Z","steps":["trace[2142042820] 'agreement among raft nodes before linearized reading' (duration: 171.003102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:11.798649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.390029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:12:11.798756Z","caller":"traceutil/trace.go:171","msg":"trace[623429941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"110.576134ms","start":"2026-04-13T21:12:11.688157Z","end":"2026-04-13T21:12:11.798733Z","steps":["trace[623429941] 'agreement among raft nodes before linearized reading' (duration: 110.365038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:11.798676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.46023Z","time spent":"338.434595ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:12:11.799029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.825435ms","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-13T21:12:11.799151Z","caller":"traceutil/trace.go:171","msg":"trace[317819156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"780.002319ms","start":"2026-04-13T21:12:11.019121Z","end":"2026-04-13T21:12:11.799124Z","steps":["trace[317819156] 'agreement among raft nodes before linearized reading' (duration: 779.340101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:11.799313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.0191Z","time spent":"780.156614ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:12:11.913189Z","caller":"traceutil/trace.go:171","msg":"trace[1429947569] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"112.205159ms","start":"2026-04-13T21:12:11.800946Z","end":"2026-04-13T21:12:11.913151Z","steps":["trace[1429947569] 'read index received' (duration: 111.1528ms)","trace[1429947569] 'applied index is now lower than readState.Index' (duration: 1.050859ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:11.91323Z","caller":"traceutil/trace.go:171","msg":"trace[2088525762] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"113.270278ms","start":"2026-04-13T21:12:11.799914Z","end":"2026-04-13T21:12:11.913184Z","steps":["trace[2088525762] 'process raft request' (duration: 112.113997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:11.913446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.430225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:11.913532Z","caller":"traceutil/trace.go:171","msg":"trace[587633076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"112.651701ms","start":"2026-04-13T21:12:11.800862Z","end":"2026-04-13T21:12:11.913514Z","steps":["trace[587633076] 'agreement among raft nodes before linearized reading' (duration: 112.394303ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:12.35142Z","caller":"traceutil/trace.go:171","msg":"trace[1789939373] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"547.158399ms","start":"2026-04-13T21:12:11.804237Z","end":"2026-04-13T21:12:12.351396Z","steps":["trace[1789939373] 'process raft request' (duration: 547.130398ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:12.351783Z","caller":"traceutil/trace.go:171","msg":"trace[1113087148] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"548.018804ms","start":"2026-04-13T21:12:11.803718Z","end":"2026-04-13T21:12:12.351737Z","steps":["trace[1113087148] 'process raft request' (duration: 547.626333ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:12.35182Z","caller":"traceutil/trace.go:171","msg":"trace[1716813119] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"549.360831ms","start":"2026-04-13T21:12:11.802409Z","end":"2026-04-13T21:12:12.351769Z","steps":["trace[1716813119] 'process raft request' (duration: 548.815776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:12.351998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.8037Z","time spent":"548.194188ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:12.351951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.804192Z","time spent":"547.303254ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:12.352067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.650247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:12:12.352148Z","caller":"traceutil/trace.go:171","msg":"trace[1498448912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"470.79418ms","start":"2026-04-13T21:12:11.881326Z","end":"2026-04-13T21:12:12.35212Z","steps":["trace[1498448912] 'agreement among raft nodes before linearized reading' (duration: 470.575814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:12.352197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.881293Z","time spent":"470.894855ms","remote":"127.0.0.1:47414","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-13T21:12:12.352183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.802385Z","time spent":"549.632957ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:12.35271Z","caller":"traceutil/trace.go:171","msg":"trace[1739985761] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:433; }","duration":"438.575371ms","start":"2026-04-13T21:12:11.913275Z","end":"2026-04-13T21:12:12.35185Z","steps":["trace[1739985761] 'read index received' (duration: 437.860151ms)","trace[1739985761] 'applied index is now lower than readState.Index' (duration: 712.64µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:12.352872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.791702ms","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-13T21:12:12.352904Z","caller":"traceutil/trace.go:171","msg":"trace[617512471] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:416; }","duration":"436.875583ms","start":"2026-04-13T21:12:11.916018Z","end":"2026-04-13T21:12:12.352893Z","steps":["trace[617512471] 'agreement among raft nodes before linearized reading' (duration: 436.795612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:12.352939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:11.915989Z","time spent":"436.942465ms","remote":"127.0.0.1:47310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-13T21:12:20.0811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098691592,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:12:20.136255Z","caller":"traceutil/trace.go:171","msg":"trace[2003899347] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"597.207011ms","start":"2026-04-13T21:12:19.539019Z","end":"2026-04-13T21:12:20.136226Z","steps":["trace[2003899347] 'process raft request' (duration: 597.030496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:20.136475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:19.538992Z","time spent":"597.371994ms","remote":"127.0.0.1:47192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:12:20.139211Z","caller":"traceutil/trace.go:171","msg":"trace[686950548] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:480; }","duration":"558.986662ms","start":"2026-04-13T21:12:19.580189Z","end":"2026-04-13T21:12:20.139176Z","steps":["trace[686950548] 'read index received' (duration: 555.875166ms)","trace[686950548] 'applied index is now lower than readState.Index' (duration: 3.110066ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:20.139295Z","caller":"traceutil/trace.go:171","msg":"trace[1492308809] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"127.774919ms","start":"2026-04-13T21:12:20.011484Z","end":"2026-04-13T21:12:20.139259Z","steps":["trace[1492308809] 'process raft request' (duration: 127.368357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:20.139387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.165526ms","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:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:20.13943Z","caller":"traceutil/trace.go:171","msg":"trace[1371127613] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:464; }","duration":"559.28088ms","start":"2026-04-13T21:12:19.580135Z","end":"2026-04-13T21:12:20.139416Z","steps":["trace[1371127613] 'agreement among raft nodes before linearized reading' (duration: 559.177037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:20.139473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:19.580112Z","time spent":"559.350652ms","remote":"127.0.0.1:47218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-13T21:12:21.126886Z","caller":"traceutil/trace.go:171","msg":"trace[956234357] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"206.90444ms","start":"2026-04-13T21:12:20.919895Z","end":"2026-04-13T21:12:21.1268Z","steps":["trace[956234357] 'process raft request' (duration: 203.754333ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:21.13081Z","caller":"traceutil/trace.go:171","msg":"trace[1558717874] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"187.145844ms","start":"2026-04-13T21:12:20.943603Z","end":"2026-04-13T21:12:21.130749Z","steps":["trace[1558717874] 'process raft request' (duration: 186.737522ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:21.130978Z","caller":"traceutil/trace.go:171","msg":"trace[1562677794] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:540; }","duration":"145.289904ms","start":"2026-04-13T21:12:20.985633Z","end":"2026-04-13T21:12:21.130923Z","steps":["trace[1562677794] 'read index received' (duration: 137.894499ms)","trace[1562677794] 'applied index is now lower than readState.Index' (duration: 7.394235ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:21.131083Z","caller":"traceutil/trace.go:171","msg":"trace[1011447863] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"178.942995ms","start":"2026-04-13T21:12:20.952124Z","end":"2026-04-13T21:12:21.131067Z","steps":["trace[1011447863] 'process raft request' (duration: 178.608916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:21.13123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.593982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:12:21.131267Z","caller":"traceutil/trace.go:171","msg":"trace[920551402] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:522; }","duration":"145.677415ms","start":"2026-04-13T21:12:20.985579Z","end":"2026-04-13T21:12:21.131257Z","steps":["trace[920551402] 'agreement among raft nodes before linearized reading' (duration: 145.473829ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:21.412877Z","caller":"traceutil/trace.go:171","msg":"trace[1708822437] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"261.256936ms","start":"2026-04-13T21:12:21.15155Z","end":"2026-04-13T21:12:21.412807Z","steps":["trace[1708822437] 'process raft request' (duration: 191.667768ms)","trace[1708822437] 'compare' (duration: 69.321851ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:21.424231Z","caller":"traceutil/trace.go:171","msg":"trace[2074252003] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"214.354786ms","start":"2026-04-13T21:12:21.209846Z","end":"2026-04-13T21:12:21.4242Z","steps":["trace[2074252003] 'process raft request' (duration: 214.218553ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:21.424749Z","caller":"traceutil/trace.go:171","msg":"trace[2100208593] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"245.352795ms","start":"2026-04-13T21:12:21.17939Z","end":"2026-04-13T21:12:21.424743Z","steps":["trace[2100208593] 'process raft request' (duration: 244.488481ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:22.655341Z","caller":"traceutil/trace.go:171","msg":"trace[1737002403] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"341.052156ms","start":"2026-04-13T21:12:22.314265Z","end":"2026-04-13T21:12:22.655317Z","steps":["trace[1737002403] 'read index received' (duration: 340.922981ms)","trace[1737002403] 'applied index is now lower than readState.Index' (duration: 128.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:22.655493Z","caller":"traceutil/trace.go:171","msg":"trace[1364206885] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"373.207797ms","start":"2026-04-13T21:12:22.282259Z","end":"2026-04-13T21:12:22.655467Z","steps":["trace[1364206885] 'process raft request' (duration: 372.909389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.655677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.282248Z","time spent":"373.31592ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:22.655713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.451208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-13T21:12:22.65582Z","caller":"traceutil/trace.go:171","msg":"trace[1292035113] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"341.588881ms","start":"2026-04-13T21:12:22.314218Z","end":"2026-04-13T21:12:22.655807Z","steps":["trace[1292035113] 'agreement among raft nodes before linearized reading' (duration: 341.373935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.65596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.314164Z","time spent":"341.776465ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7730,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-13T21:12:22.830332Z","caller":"traceutil/trace.go:171","msg":"trace[423211508] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"174.592926ms","start":"2026-04-13T21:12:22.655528Z","end":"2026-04-13T21:12:22.83012Z","steps":["trace[423211508] 'read index received' (duration: 171.330895ms)","trace[423211508] 'applied index is now lower than readState.Index' (duration: 3.260611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:22.830538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.756073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:22.830699Z","caller":"traceutil/trace.go:171","msg":"trace[582978894] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"261.01326ms","start":"2026-04-13T21:12:22.569648Z","end":"2026-04-13T21:12:22.830661Z","steps":["trace[582978894] 'agreement among raft nodes before linearized reading' (duration: 260.492525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.830743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.386445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T21:12:22.830728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.297068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:12:22.830816Z","caller":"traceutil/trace.go:171","msg":"trace[1756656708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"432.518019ms","start":"2026-04-13T21:12:22.398281Z","end":"2026-04-13T21:12:22.830799Z","steps":["trace[1756656708] 'agreement among raft nodes before linearized reading' (duration: 432.205911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.830732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.962751ms","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-13T21:12:22.830854Z","caller":"traceutil/trace.go:171","msg":"trace[1748463319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"417.480702ms","start":"2026-04-13T21:12:22.413342Z","end":"2026-04-13T21:12:22.830822Z","steps":["trace[1748463319] 'agreement among raft nodes before linearized reading' (duration: 417.189513ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:22.830876Z","caller":"traceutil/trace.go:171","msg":"trace[382563332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"429.109324ms","start":"2026-04-13T21:12:22.40175Z","end":"2026-04-13T21:12:22.830859Z","steps":["trace[382563332] 'agreement among raft nodes before linearized reading' (duration: 428.678132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.830929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.413293Z","time spent":"417.619206ms","remote":"127.0.0.1:47414","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-13T21:12:22.830948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.398258Z","time spent":"432.60062ms","remote":"127.0.0.1:47414","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-13T21:12:22.83164Z","caller":"traceutil/trace.go:171","msg":"trace[289490978] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"472.548637ms","start":"2026-04-13T21:12:22.359051Z","end":"2026-04-13T21:12:22.8316Z","steps":["trace[289490978] 'process raft request' (duration: 468.532696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.83182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.141451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:22.832961Z","caller":"traceutil/trace.go:171","msg":"trace[1758917211] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"463.473097ms","start":"2026-04-13T21:12:22.369449Z","end":"2026-04-13T21:12:22.832922Z","steps":["trace[1758917211] 'agreement among raft nodes before linearized reading' (duration: 461.386249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.832755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.755238ms","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-13T21:12:22.833062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.369421Z","time spent":"463.588979ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-04-13T21:12:22.832101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.359007Z","time spent":"472.769094ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:22.830957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.401694Z","time spent":"429.248969ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:12:22.83357Z","caller":"traceutil/trace.go:171","msg":"trace[400036542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"421.00808ms","start":"2026-04-13T21:12:22.412033Z","end":"2026-04-13T21:12:22.833041Z","steps":["trace[400036542] 'agreement among raft nodes before linearized reading' (duration: 418.334286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:22.836499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.412023Z","time spent":"424.437526ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:12:23.17583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.307431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:12:23.176156Z","caller":"traceutil/trace.go:171","msg":"trace[2101842457] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"345.698544ms","start":"2026-04-13T21:12:22.830438Z","end":"2026-04-13T21:12:23.176137Z","steps":["trace[2101842457] 'read index received' (duration: 198.924539ms)","trace[2101842457] 'applied index is now lower than readState.Index' (duration: 146.772925ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:23.176339Z","caller":"traceutil/trace.go:171","msg":"trace[1415640735] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"514.055008ms","start":"2026-04-13T21:12:22.662223Z","end":"2026-04-13T21:12:23.176278Z","steps":["trace[1415640735] 'process raft request' (duration: 367.040346ms)","trace[1415640735] 'compare' (duration: 146.143298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:23.176419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.275693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-04-13T21:12:23.176533Z","caller":"traceutil/trace.go:171","msg":"trace[1024318669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:559; }","duration":"514.419338ms","start":"2026-04-13T21:12:22.662099Z","end":"2026-04-13T21:12:23.176518Z","steps":["trace[1024318669] 'agreement among raft nodes before linearized reading' (duration: 514.169271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.176537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.808865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-g7n8g\" ","response":"range_response_count:1 size:4683"} {"level":"warn","ts":"2026-04-13T21:12:23.176609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.662077Z","time spent":"514.5181ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31167,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-13T21:12:23.176671Z","caller":"traceutil/trace.go:171","msg":"trace[725713999] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-g7n8g; range_end:; response_count:1; response_revision:559; }","duration":"386.01714ms","start":"2026-04-13T21:12:22.790625Z","end":"2026-04-13T21:12:23.176642Z","steps":["trace[725713999] 'agreement among raft nodes before linearized reading' (duration: 385.64823ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:23.176733Z","caller":"traceutil/trace.go:171","msg":"trace[1805584850] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"403.504826ms","start":"2026-04-13T21:12:22.773216Z","end":"2026-04-13T21:12:23.176721Z","steps":["trace[1805584850] 'process raft request' (duration: 402.855928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.17679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.79057Z","time spent":"386.205407ms","remote":"127.0.0.1:47330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-g7n8g\" "} {"level":"warn","ts":"2026-04-13T21:12:23.176798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.773187Z","time spent":"403.584538ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:12:23.17698Z","caller":"traceutil/trace.go:171","msg":"trace[876758579] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"336.692714ms","start":"2026-04-13T21:12:22.840275Z","end":"2026-04-13T21:12:23.176968Z","steps":["trace[876758579] 'process raft request' (duration: 336.16492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.17658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.662204Z","time spent":"514.247033ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:23.177045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.840237Z","time spent":"336.774407ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:23.176998Z","caller":"traceutil/trace.go:171","msg":"trace[1859665706] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"336.509499ms","start":"2026-04-13T21:12:22.84045Z","end":"2026-04-13T21:12:23.17696Z","steps":["trace[1859665706] 'process raft request' (duration: 336.378416ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:23.177168Z","caller":"traceutil/trace.go:171","msg":"trace[660482224] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"335.620825ms","start":"2026-04-13T21:12:22.841536Z","end":"2026-04-13T21:12:23.177157Z","steps":["trace[660482224] 'process raft request' (duration: 335.373168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.177225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.841526Z","time spent":"335.671755ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:23.177306Z","caller":"traceutil/trace.go:171","msg":"trace[971190709] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"336.91986ms","start":"2026-04-13T21:12:22.840376Z","end":"2026-04-13T21:12:23.177296Z","steps":["trace[971190709] 'process raft request' (duration: 336.374365ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:23.17733Z","caller":"traceutil/trace.go:171","msg":"trace[1994080445] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"302.565239ms","start":"2026-04-13T21:12:22.874739Z","end":"2026-04-13T21:12:23.177304Z","steps":["trace[1994080445] 'process raft request' (duration: 302.274432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.177366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.840366Z","time spent":"336.968963ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:23.177375Z","caller":"traceutil/trace.go:171","msg":"trace[301542333] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"204.980837ms","start":"2026-04-13T21:12:22.972373Z","end":"2026-04-13T21:12:23.177354Z","steps":["trace[301542333] 'process raft request' (duration: 204.716489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:23.17734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.840422Z","time spent":"336.739997ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:23.177444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:22.874703Z","time spent":"302.688263ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:24.482827Z","caller":"traceutil/trace.go:171","msg":"trace[1592377395] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"113.199725ms","start":"2026-04-13T21:12:24.369611Z","end":"2026-04-13T21:12:24.48281Z","steps":["trace[1592377395] 'read index received' (duration: 113.03685ms)","trace[1592377395] 'applied index is now lower than readState.Index' (duration: 162.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:24.483115Z","caller":"traceutil/trace.go:171","msg":"trace[2003335259] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"285.772525ms","start":"2026-04-13T21:12:24.197332Z","end":"2026-04-13T21:12:24.483104Z","steps":["trace[2003335259] 'process raft request' (duration: 285.369874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:24.48352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.805931ms","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-13T21:12:24.483792Z","caller":"traceutil/trace.go:171","msg":"trace[1780998769] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"114.184952ms","start":"2026-04-13T21:12:24.369556Z","end":"2026-04-13T21:12:24.483741Z","steps":["trace[1780998769] 'agreement among raft nodes before linearized reading' (duration: 113.572836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.696631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.55075ms","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-13T21:12:28.696719Z","caller":"traceutil/trace.go:171","msg":"trace[1100712711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"163.694423ms","start":"2026-04-13T21:12:28.533011Z","end":"2026-04-13T21:12:28.696705Z","steps":["trace[1100712711] 'range keys from in-memory index tree' (duration: 163.368395ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:28.696893Z","caller":"traceutil/trace.go:171","msg":"trace[1969814651] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"162.585043ms","start":"2026-04-13T21:12:28.534267Z","end":"2026-04-13T21:12:28.696852Z","steps":["trace[1969814651] 'process raft request' (duration: 63.468859ms)","trace[1969814651] 'compare' (duration: 98.785505ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:28.697028Z","caller":"traceutil/trace.go:171","msg":"trace[1627752180] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"157.933574ms","start":"2026-04-13T21:12:28.538739Z","end":"2026-04-13T21:12:28.696673Z","steps":["trace[1627752180] 'read index received' (duration: 58.984144ms)","trace[1627752180] 'applied index is now lower than readState.Index' (duration: 98.9484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:28.697283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.47729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:28.697364Z","caller":"traceutil/trace.go:171","msg":"trace[1880648803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"158.558952ms","start":"2026-04-13T21:12:28.538788Z","end":"2026-04-13T21:12:28.697347Z","steps":["trace[1880648803] 'agreement among raft nodes before linearized reading' (duration: 158.449729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.698059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.770219ms","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":"info","ts":"2026-04-13T21:12:28.698124Z","caller":"traceutil/trace.go:171","msg":"trace[1225274278] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:623; }","duration":"114.869901ms","start":"2026-04-13T21:12:28.583236Z","end":"2026-04-13T21:12:28.698106Z","steps":["trace[1225274278] 'agreement among raft nodes before linearized reading' (duration: 114.762978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.698274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.866451ms","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":"info","ts":"2026-04-13T21:12:28.698357Z","caller":"traceutil/trace.go:171","msg":"trace[880907175] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:623; }","duration":"114.947584ms","start":"2026-04-13T21:12:28.583392Z","end":"2026-04-13T21:12:28.69834Z","steps":["trace[880907175] 'agreement among raft nodes before linearized reading' (duration: 114.845891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.698418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.129659ms","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":"info","ts":"2026-04-13T21:12:28.698494Z","caller":"traceutil/trace.go:171","msg":"trace[905895272] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:623; }","duration":"115.213661ms","start":"2026-04-13T21:12:28.583268Z","end":"2026-04-13T21:12:28.698481Z","steps":["trace[905895272] 'agreement among raft nodes before linearized reading' (duration: 115.114098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.698601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.464377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:28.698702Z","caller":"traceutil/trace.go:171","msg":"trace[1094345365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"159.598471ms","start":"2026-04-13T21:12:28.539075Z","end":"2026-04-13T21:12:28.698674Z","steps":["trace[1094345365] 'agreement among raft nodes before linearized reading' (duration: 159.470378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:28.697382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.555431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:28.698882Z","caller":"traceutil/trace.go:171","msg":"trace[362256296] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"160.120565ms","start":"2026-04-13T21:12:28.53871Z","end":"2026-04-13T21:12:28.698831Z","steps":["trace[362256296] 'agreement among raft nodes before linearized reading' (duration: 158.389676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:31.728481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098691971,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:12:32.020029Z","caller":"traceutil/trace.go:171","msg":"trace[1694540619] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"792.54085ms","start":"2026-04-13T21:12:31.227458Z","end":"2026-04-13T21:12:32.019999Z","steps":["trace[1694540619] 'read index received' (duration: 792.266883ms)","trace[1694540619] 'applied index is now lower than readState.Index' (duration: 273.167µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:32.020369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.658291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:32.020413Z","caller":"traceutil/trace.go:171","msg":"trace[505387730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"559.770045ms","start":"2026-04-13T21:12:31.46063Z","end":"2026-04-13T21:12:32.0204Z","steps":["trace[505387730] 'agreement among raft nodes before linearized reading' (duration: 559.676902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:32.02041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.950624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gjq4q\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-13T21:12:32.020448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:31.460601Z","time spent":"559.839386ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:12:32.020504Z","caller":"traceutil/trace.go:171","msg":"trace[1912582929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gjq4q; range_end:; response_count:1; response_revision:642; }","duration":"202.115968ms","start":"2026-04-13T21:12:31.818354Z","end":"2026-04-13T21:12:32.02047Z","steps":["trace[1912582929] 'agreement among raft nodes before linearized reading' (duration: 201.8535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:32.02061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.80968ms","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-13T21:12:32.020697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.896228ms","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-13T21:12:32.020726Z","caller":"traceutil/trace.go:171","msg":"trace[1702131466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"789.921989ms","start":"2026-04-13T21:12:31.230794Z","end":"2026-04-13T21:12:32.020716Z","steps":["trace[1702131466] 'agreement among raft nodes before linearized reading' (duration: 789.368383ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:32.020731Z","caller":"traceutil/trace.go:171","msg":"trace[355516804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"787.974644ms","start":"2026-04-13T21:12:31.23273Z","end":"2026-04-13T21:12:32.020705Z","steps":["trace[355516804] 'agreement among raft nodes before linearized reading' (duration: 787.604914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:32.020745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:31.230711Z","time spent":"790.029491ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:12:32.020802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:31.23272Z","time spent":"788.069517ms","remote":"127.0.0.1:47414","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":"info","ts":"2026-04-13T21:12:32.020159Z","caller":"traceutil/trace.go:171","msg":"trace[1211090412] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"796.421528ms","start":"2026-04-13T21:12:31.223673Z","end":"2026-04-13T21:12:32.020094Z","steps":["trace[1211090412] 'process raft request' (duration: 796.11993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:32.021092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:31.223634Z","time spent":"797.351853ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:32.021343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.854527ms","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-13T21:12:32.021471Z","caller":"traceutil/trace.go:171","msg":"trace[212982660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"793.994671ms","start":"2026-04-13T21:12:31.227432Z","end":"2026-04-13T21:12:32.021426Z","steps":["trace[212982660] 'agreement among raft nodes before linearized reading' (duration: 792.698805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:32.021594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:31.227418Z","time spent":"794.147675ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:12:32.208009Z","caller":"traceutil/trace.go:171","msg":"trace[670136940] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"180.87444ms","start":"2026-04-13T21:12:32.027118Z","end":"2026-04-13T21:12:32.207993Z","steps":["trace[670136940] 'process raft request' (duration: 180.775997ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:32.207991Z","caller":"traceutil/trace.go:171","msg":"trace[1911760934] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"181.460786ms","start":"2026-04-13T21:12:32.026505Z","end":"2026-04-13T21:12:32.207966Z","steps":["trace[1911760934] 'process raft request' (duration: 181.190468ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:32.208239Z","caller":"traceutil/trace.go:171","msg":"trace[1883320252] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"174.169564ms","start":"2026-04-13T21:12:32.034056Z","end":"2026-04-13T21:12:32.208226Z","steps":["trace[1883320252] 'process raft request' (duration: 173.882306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:33.959635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098691981,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:12:34.163003Z","caller":"traceutil/trace.go:171","msg":"trace[1618469799] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"704.400209ms","start":"2026-04-13T21:12:33.458561Z","end":"2026-04-13T21:12:34.162961Z","steps":["trace[1618469799] 'read index received' (duration: 652.406378ms)","trace[1618469799] 'applied index is now lower than readState.Index' (duration: 51.991631ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:34.163319Z","caller":"traceutil/trace.go:171","msg":"trace[793939010] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"716.017732ms","start":"2026-04-13T21:12:33.447287Z","end":"2026-04-13T21:12:34.163305Z","steps":["trace[793939010] 'process raft request' (duration: 663.666851ms)","trace[793939010] 'compare' (duration: 50.779657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:34.163433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:33.447252Z","time spent":"716.093172ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:34.163603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.042967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:34.163628Z","caller":"traceutil/trace.go:171","msg":"trace[892862511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"705.097048ms","start":"2026-04-13T21:12:33.458523Z","end":"2026-04-13T21:12:34.16362Z","steps":["trace[892862511] 'agreement among raft nodes before linearized reading' (duration: 705.049816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:34.163647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:33.458497Z","time spent":"705.14645ms","remote":"127.0.0.1:47092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:12:34.163847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.643622ms","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-13T21:12:34.163888Z","caller":"traceutil/trace.go:171","msg":"trace[1367682184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"137.718995ms","start":"2026-04-13T21:12:34.02614Z","end":"2026-04-13T21:12:34.163859Z","steps":["trace[1367682184] 'agreement among raft nodes before linearized reading' (duration: 137.597061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:34.164062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.338464ms","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-13T21:12:34.16408Z","caller":"traceutil/trace.go:171","msg":"trace[1977849846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"111.398535ms","start":"2026-04-13T21:12:34.052676Z","end":"2026-04-13T21:12:34.164074Z","steps":["trace[1977849846] 'agreement among raft nodes before linearized reading' (duration: 111.335912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:35.079113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.452657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:12:35.079597Z","caller":"traceutil/trace.go:171","msg":"trace[1818555380] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"910.704794ms","start":"2026-04-13T21:12:34.168872Z","end":"2026-04-13T21:12:35.079577Z","steps":["trace[1818555380] 'process raft request' (duration: 194.66772ms)","trace[1818555380] 'compare' (duration: 715.129708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:35.079684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.168851Z","time spent":"910.800637ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:35.079711Z","caller":"traceutil/trace.go:171","msg":"trace[259170862] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"909.855351ms","start":"2026-04-13T21:12:34.169822Z","end":"2026-04-13T21:12:35.079678Z","steps":["trace[259170862] 'process raft request' (duration: 909.432759ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:35.0798Z","caller":"traceutil/trace.go:171","msg":"trace[1582906692] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"865.725769ms","start":"2026-04-13T21:12:34.213847Z","end":"2026-04-13T21:12:35.079573Z","steps":["trace[1582906692] 'read index received' (duration: 149.604122ms)","trace[1582906692] 'applied index is now lower than readState.Index' (duration: 716.120277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:35.079944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.169788Z","time spent":"910.016535ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:35.080056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.201691ms","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-13T21:12:35.080137Z","caller":"traceutil/trace.go:171","msg":"trace[1532223068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"866.324095ms","start":"2026-04-13T21:12:34.213795Z","end":"2026-04-13T21:12:35.080119Z","steps":["trace[1532223068] 'agreement among raft nodes before linearized reading' (duration: 866.119969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:35.080193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.213765Z","time spent":"866.410878ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:12:35.080349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.440243ms","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":"warn","ts":"2026-04-13T21:12:35.080368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.403887ms","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-13T21:12:35.080443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.225825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:35.080479Z","caller":"traceutil/trace.go:171","msg":"trace[868085086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"618.296106ms","start":"2026-04-13T21:12:34.462173Z","end":"2026-04-13T21:12:35.080469Z","steps":["trace[868085086] 'agreement among raft nodes before linearized reading' (duration: 618.250345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:35.080508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.462147Z","time spent":"618.356258ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:12:35.080384Z","caller":"traceutil/trace.go:171","msg":"trace[1903758135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:650; }","duration":"300.546264ms","start":"2026-04-13T21:12:34.779828Z","end":"2026-04-13T21:12:35.080374Z","steps":["trace[1903758135] 'agreement among raft nodes before linearized reading' (duration: 300.445952ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:35.080464Z","caller":"traceutil/trace.go:171","msg":"trace[978632529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"866.543621ms","start":"2026-04-13T21:12:34.213903Z","end":"2026-04-13T21:12:35.080446Z","steps":["trace[978632529] 'agreement among raft nodes before linearized reading' (duration: 866.204911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:35.080575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.213896Z","time spent":"866.668014ms","remote":"127.0.0.1:47414","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-13T21:12:35.080571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:34.77979Z","time spent":"300.765112ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T21:12:45.300568Z","caller":"traceutil/trace.go:171","msg":"trace[1853489809] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:738; }","duration":"138.883517ms","start":"2026-04-13T21:12:45.161649Z","end":"2026-04-13T21:12:45.300533Z","steps":["trace[1853489809] 'read index received' (duration: 138.584619ms)","trace[1853489809] 'applied index is now lower than readState.Index' (duration: 297.328µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:45.300618Z","caller":"traceutil/trace.go:171","msg":"trace[190109582] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"165.934666ms","start":"2026-04-13T21:12:45.134641Z","end":"2026-04-13T21:12:45.300576Z","steps":["trace[190109582] 'process raft request' (duration: 165.551195ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:45.300666Z","caller":"traceutil/trace.go:171","msg":"trace[35851382] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"165.973477ms","start":"2026-04-13T21:12:45.134668Z","end":"2026-04-13T21:12:45.300641Z","steps":["trace[35851382] 'process raft request' (duration: 165.793512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:45.301006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.014354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-13T21:12:45.301007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.335069ms","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-13T21:12:45.301057Z","caller":"traceutil/trace.go:171","msg":"trace[1300592791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"133.106297ms","start":"2026-04-13T21:12:45.167937Z","end":"2026-04-13T21:12:45.301043Z","steps":["trace[1300592791] 'agreement among raft nodes before linearized reading' (duration: 132.949003ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:45.301113Z","caller":"traceutil/trace.go:171","msg":"trace[1007639378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"139.437471ms","start":"2026-04-13T21:12:45.161618Z","end":"2026-04-13T21:12:45.301056Z","steps":["trace[1007639378] 'agreement among raft nodes before linearized reading' (duration: 139.125292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:45.302088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.057347ms","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-13T21:12:45.302173Z","caller":"traceutil/trace.go:171","msg":"trace[665755383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"137.165919ms","start":"2026-04-13T21:12:45.164988Z","end":"2026-04-13T21:12:45.302154Z","steps":["trace[665755383] 'agreement among raft nodes before linearized reading' (duration: 135.876343ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:45.464907Z","caller":"traceutil/trace.go:171","msg":"trace[439223712] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"158.424168ms","start":"2026-04-13T21:12:45.306404Z","end":"2026-04-13T21:12:45.464828Z","steps":["trace[439223712] 'process raft request' (duration: 118.099592ms)","trace[439223712] 'compare' (duration: 40.052119ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:45.464971Z","caller":"traceutil/trace.go:171","msg":"trace[1922955157] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"158.075429ms","start":"2026-04-13T21:12:45.306844Z","end":"2026-04-13T21:12:45.464919Z","steps":["trace[1922955157] 'process raft request' (duration: 157.846052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:52.435055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.884039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:52.435156Z","caller":"traceutil/trace.go:171","msg":"trace[1704696844] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:762; }","duration":"302.067974ms","start":"2026-04-13T21:12:52.133069Z","end":"2026-04-13T21:12:52.435137Z","steps":["trace[1704696844] 'count revisions from in-memory index tree' (duration: 301.762165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:52.435185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:52.133034Z","time spent":"302.142696ms","remote":"127.0.0.1:47658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:12:52.435235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.254154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qnbxp\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-13T21:12:52.435311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.886656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:52.435329Z","caller":"traceutil/trace.go:171","msg":"trace[1873181596] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:762; }","duration":"367.937718ms","start":"2026-04-13T21:12:52.067386Z","end":"2026-04-13T21:12:52.435324Z","steps":["trace[1873181596] 'count revisions from in-memory index tree' (duration: 367.790954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:52.435346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:52.067365Z","time spent":"367.976719ms","remote":"127.0.0.1:47192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":73,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:12:52.435295Z","caller":"traceutil/trace.go:171","msg":"trace[37127870] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qnbxp; range_end:; response_count:1; response_revision:762; }","duration":"491.348586ms","start":"2026-04-13T21:12:51.943928Z","end":"2026-04-13T21:12:52.435277Z","steps":["trace[37127870] 'range keys from in-memory index tree' (duration: 491.030867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:52.435383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:51.943902Z","time spent":"491.464959ms","remote":"127.0.0.1:47330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qnbxp\" "} {"level":"warn","ts":"2026-04-13T21:12:52.435476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.399946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:52.43553Z","caller":"traceutil/trace.go:171","msg":"trace[1639318010] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:762; }","duration":"479.482227ms","start":"2026-04-13T21:12:51.956031Z","end":"2026-04-13T21:12:52.435514Z","steps":["trace[1639318010] 'count revisions from in-memory index tree' (duration: 479.252491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:52.43558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:51.956019Z","time spent":"479.5452ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:12:53.118121Z","caller":"traceutil/trace.go:171","msg":"trace[2048357429] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"675.357784ms","start":"2026-04-13T21:12:52.44274Z","end":"2026-04-13T21:12:53.118097Z","steps":["trace[2048357429] 'process raft request' (duration: 675.19584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:53.118291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:52.442709Z","time spent":"675.493777ms","remote":"127.0.0.1:47330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:01.506353Z","caller":"traceutil/trace.go:171","msg":"trace[1522081375] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"119.18747ms","start":"2026-04-13T21:13:01.387136Z","end":"2026-04-13T21:13:01.506324Z","steps":["trace[1522081375] 'read index received' (duration: 119.034196ms)","trace[1522081375] 'applied index is now lower than readState.Index' (duration: 152.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:01.506805Z","caller":"traceutil/trace.go:171","msg":"trace[759781350] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"121.66502ms","start":"2026-04-13T21:13:01.385066Z","end":"2026-04-13T21:13:01.506731Z","steps":["trace[759781350] 'process raft request' (duration: 121.100684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:01.507284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.090536ms","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-13T21:13:01.507354Z","caller":"traceutil/trace.go:171","msg":"trace[1252466577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"120.22132ms","start":"2026-04-13T21:13:01.387116Z","end":"2026-04-13T21:13:01.507337Z","steps":["trace[1252466577] 'agreement among raft nodes before linearized reading' (duration: 119.490829ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:05.705689Z","caller":"traceutil/trace.go:171","msg":"trace[1105049240] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"109.187165ms","start":"2026-04-13T21:13:05.596473Z","end":"2026-04-13T21:13:05.70566Z","steps":["trace[1105049240] 'process raft request' (duration: 109.139244ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:05.706089Z","caller":"traceutil/trace.go:171","msg":"trace[830616460] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"109.875853ms","start":"2026-04-13T21:13:05.596201Z","end":"2026-04-13T21:13:05.706077Z","steps":["trace[830616460] 'process raft request' (duration: 109.200145ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:05.706526Z","caller":"traceutil/trace.go:171","msg":"trace[1581410665] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"110.209942ms","start":"2026-04-13T21:13:05.596273Z","end":"2026-04-13T21:13:05.706483Z","steps":["trace[1581410665] 'process raft request' (duration: 109.299936ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:13.926324Z","caller":"traceutil/trace.go:171","msg":"trace[170655119] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"177.868386ms","start":"2026-04-13T21:13:13.748426Z","end":"2026-04-13T21:13:13.926294Z","steps":["trace[170655119] 'process raft request' (duration: 177.64547ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:13.926568Z","caller":"traceutil/trace.go:171","msg":"trace[395949341] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"174.866612ms","start":"2026-04-13T21:13:13.751692Z","end":"2026-04-13T21:13:13.926558Z","steps":["trace[395949341] 'process raft request' (duration: 174.560054ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:14.377309Z","caller":"traceutil/trace.go:171","msg":"trace[660132922] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"100.438891ms","start":"2026-04-13T21:13:14.276831Z","end":"2026-04-13T21:13:14.37727Z","steps":["trace[660132922] 'process raft request' (duration: 100.220685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.354723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098692671,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:13:30.798908Z","caller":"traceutil/trace.go:171","msg":"trace[138273573] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"944.978601ms","start":"2026-04-13T21:13:29.853891Z","end":"2026-04-13T21:13:30.798869Z","steps":["trace[138273573] 'read index received' (duration: 944.284323ms)","trace[138273573] 'applied index is now lower than readState.Index' (duration: 693.298µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:30.798942Z","caller":"traceutil/trace.go:171","msg":"trace[703114194] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"992.0581ms","start":"2026-04-13T21:13:29.806851Z","end":"2026-04-13T21:13:30.798909Z","steps":["trace[703114194] 'process raft request' (duration: 991.825964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.799131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.227939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:13:30.799164Z","caller":"traceutil/trace.go:171","msg":"trace[332667621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"945.328302ms","start":"2026-04-13T21:13:29.853829Z","end":"2026-04-13T21:13:30.799157Z","steps":["trace[332667621] 'agreement among raft nodes before linearized reading' (duration: 945.182147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.799186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.853807Z","time spent":"945.373643ms","remote":"127.0.0.1:47414","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-13T21:13:30.799206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.806839Z","time spent":"992.246255ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:31.299677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098692674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:13:31.891741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.285614ms","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-13T21:13:31.89192Z","caller":"traceutil/trace.go:171","msg":"trace[231206060] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:985; }","duration":"441.49748ms","start":"2026-04-13T21:13:31.450399Z","end":"2026-04-13T21:13:31.891897Z","steps":["trace[231206060] 'range keys from in-memory index tree' (duration: 441.256594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.892402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.402036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:13:31.892696Z","caller":"traceutil/trace.go:171","msg":"trace[1552916641] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"1.093649653s","start":"2026-04-13T21:13:30.799023Z","end":"2026-04-13T21:13:31.892673Z","steps":["trace[1552916641] 'read index received' (duration: 671.774023ms)","trace[1552916641] 'applied index is now lower than readState.Index' (duration: 421.87458ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:31.892804Z","caller":"traceutil/trace.go:171","msg":"trace[72340465] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.438678743s","start":"2026-04-13T21:13:30.454112Z","end":"2026-04-13T21:13:31.892791Z","steps":["trace[72340465] 'process raft request' (duration: 1.016710221s)","trace[72340465] 'compare' (duration: 421.226941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:31.892879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.454074Z","time spent":"1.438761004s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:31.89326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790570201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-13T21:13:31.893303Z","caller":"traceutil/trace.go:171","msg":"trace[366916155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"1.790662814s","start":"2026-04-13T21:13:30.102627Z","end":"2026-04-13T21:13:31.89329Z","steps":["trace[366916155] 'agreement among raft nodes before linearized reading' (duration: 1.790483659s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.893337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.1026Z","time spent":"1.790729476s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T21:13:31.893359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767208436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T21:13:31.89348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9855221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:13:31.893484Z","caller":"traceutil/trace.go:171","msg":"trace[708934876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"1.767410762s","start":"2026-04-13T21:13:30.126047Z","end":"2026-04-13T21:13:31.893458Z","steps":["trace[708934876] 'agreement among raft nodes before linearized reading' (duration: 1.767044321s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:31.893521Z","caller":"traceutil/trace.go:171","msg":"trace[1049023964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"1.985603602s","start":"2026-04-13T21:13:29.907907Z","end":"2026-04-13T21:13:31.893511Z","steps":["trace[1049023964] 'agreement among raft nodes before linearized reading' (duration: 1.985498769s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.893542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.126022Z","time spent":"1.767509876s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:13:31.893556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.907857Z","time spent":"1.985693625s","remote":"127.0.0.1:47414","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-13T21:13:31.893522Z","caller":"traceutil/trace.go:171","msg":"trace[336997789] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.089689934s","start":"2026-04-13T21:13:30.80379Z","end":"2026-04-13T21:13:31.89348Z","steps":["trace[336997789] 'process raft request' (duration: 1.088756908s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.893721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.803758Z","time spent":"1.089857319s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:31.893852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434311162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:31.894017Z","caller":"traceutil/trace.go:171","msg":"trace[1277679469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"1.434632861s","start":"2026-04-13T21:13:30.459354Z","end":"2026-04-13T21:13:31.893986Z","steps":["trace[1277679469] 'agreement among raft nodes before linearized reading' (duration: 1.43426684s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.894166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.459338Z","time spent":"1.434811007s","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:13:31.895397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.106694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:13:31.895458Z","caller":"traceutil/trace.go:171","msg":"trace[1605772712] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:988; }","duration":"904.201747ms","start":"2026-04-13T21:13:30.99124Z","end":"2026-04-13T21:13:31.895442Z","steps":["trace[1605772712] 'agreement among raft nodes before linearized reading' (duration: 904.000622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.895503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.991214Z","time spent":"904.281099ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.156\" "} {"level":"warn","ts":"2026-04-13T21:13:31.895785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.770113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:31.895842Z","caller":"traceutil/trace.go:171","msg":"trace[1609332018] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:988; }","duration":"501.889856ms","start":"2026-04-13T21:13:31.393938Z","end":"2026-04-13T21:13:31.895828Z","steps":["trace[1609332018] 'agreement among raft nodes before linearized reading' (duration: 501.808744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.895916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:31.393895Z","time spent":"502.011921ms","remote":"127.0.0.1:47278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:13:31.896043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.242306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:31.896132Z","caller":"traceutil/trace.go:171","msg":"trace[1577246321] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:988; }","duration":"656.36008ms","start":"2026-04-13T21:13:31.239752Z","end":"2026-04-13T21:13:31.896112Z","steps":["trace[1577246321] 'agreement among raft nodes before linearized reading' (duration: 656.245975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:31.896196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:31.239729Z","time spent":"656.453401ms","remote":"127.0.0.1:57446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:13:36.135124Z","caller":"traceutil/trace.go:171","msg":"trace[1006004960] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"169.486886ms","start":"2026-04-13T21:13:35.965603Z","end":"2026-04-13T21:13:36.135089Z","steps":["trace[1006004960] 'read index received' (duration: 169.321751ms)","trace[1006004960] 'applied index is now lower than readState.Index' (duration: 164.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:36.135229Z","caller":"traceutil/trace.go:171","msg":"trace[500295759] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"173.653121ms","start":"2026-04-13T21:13:35.961532Z","end":"2026-04-13T21:13:36.135185Z","steps":["trace[500295759] 'process raft request' (duration: 173.373394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:36.135536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.897707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:13:36.135639Z","caller":"traceutil/trace.go:171","msg":"trace[1387687312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"170.061242ms","start":"2026-04-13T21:13:35.965555Z","end":"2026-04-13T21:13:36.135616Z","steps":["trace[1387687312] 'agreement among raft nodes before linearized reading' (duration: 169.728712ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:36.141025Z","caller":"traceutil/trace.go:171","msg":"trace[1978052035] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"166.524144ms","start":"2026-04-13T21:13:35.97446Z","end":"2026-04-13T21:13:36.140984Z","steps":["trace[1978052035] 'process raft request' (duration: 166.329308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:36.14108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.360013ms","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-13T21:13:36.141149Z","caller":"traceutil/trace.go:171","msg":"trace[712310657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"173.474516ms","start":"2026-04-13T21:13:35.96766Z","end":"2026-04-13T21:13:36.141135Z","steps":["trace[712310657] 'agreement among raft nodes before linearized reading' (duration: 173.286251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:36.141173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.532772ms","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-13T21:13:36.141258Z","caller":"traceutil/trace.go:171","msg":"trace[1834107313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"171.686957ms","start":"2026-04-13T21:13:35.969552Z","end":"2026-04-13T21:13:36.141239Z","steps":["trace[1834107313] 'agreement among raft nodes before linearized reading' (duration: 171.42716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:36.141302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.56285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:13:36.141322Z","caller":"traceutil/trace.go:171","msg":"trace[578164987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"172.594421ms","start":"2026-04-13T21:13:35.968723Z","end":"2026-04-13T21:13:36.141317Z","steps":["trace[578164987] 'agreement among raft nodes before linearized reading' (duration: 172.54548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:47.308177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.92034ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-13T21:13:56.514471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.794044ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T21:13:56.515088Z","caller":"traceutil/trace.go:171","msg":"trace[1848211367] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"278.716609ms","start":"2026-04-13T21:13:56.236345Z","end":"2026-04-13T21:13:56.515061Z","steps":["trace[1848211367] 'read index received' (duration: 58.35407ms)","trace[1848211367] 'applied index is now lower than readState.Index' (duration: 220.360449ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:56.515302Z","caller":"traceutil/trace.go:171","msg":"trace[1242819142] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"295.828692ms","start":"2026-04-13T21:13:56.219434Z","end":"2026-04-13T21:13:56.515263Z","steps":["trace[1242819142] 'process raft request' (duration: 295.185464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:56.528418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.067699ms","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-13T21:13:56.528459Z","caller":"traceutil/trace.go:171","msg":"trace[1905297334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"292.186811ms","start":"2026-04-13T21:13:56.23626Z","end":"2026-04-13T21:13:56.528447Z","steps":["trace[1905297334] 'agreement among raft nodes before linearized reading' (duration: 279.015638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:56.528597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.887076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:13:56.528623Z","caller":"traceutil/trace.go:171","msg":"trace[533980946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"165.984371ms","start":"2026-04-13T21:13:56.362632Z","end":"2026-04-13T21:13:56.528616Z","steps":["trace[533980946] 'agreement among raft nodes before linearized reading' (duration: 152.672533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:56.528611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.377087ms","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-13T21:13:56.528696Z","caller":"traceutil/trace.go:171","msg":"trace[1893228375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"105.48277ms","start":"2026-04-13T21:13:56.423194Z","end":"2026-04-13T21:13:56.528677Z","steps":["trace[1893228375] 'agreement among raft nodes before linearized reading' (duration: 92.190263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:57.48192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098692897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:13:57.574042Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030007937s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:13:57.574767Z","caller":"traceutil/trace.go:171","msg":"trace[1624497069] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"1.041544366s","start":"2026-04-13T21:13:56.533173Z","end":"2026-04-13T21:13:57.574717Z","steps":["trace[1624497069] 'process raft request' (duration: 1.041177806s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:57.574831Z","caller":"traceutil/trace.go:171","msg":"trace[91677373] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"1.041055623s","start":"2026-04-13T21:13:56.53372Z","end":"2026-04-13T21:13:57.574775Z","steps":["trace[91677373] 'process raft request' (duration: 1.040872087s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:57.575161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:56.533136Z","time spent":"1.041908926s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:57.574798Z","caller":"traceutil/trace.go:171","msg":"trace[1629937013] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.040875598s","start":"2026-04-13T21:13:56.533883Z","end":"2026-04-13T21:13:57.574759Z","steps":["trace[1629937013] 'process raft request' (duration: 1.040761714s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:57.575585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:56.533706Z","time spent":"1.041432962s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:57.575816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:56.533874Z","time spent":"1.041436994s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:58.231641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.561439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:13:58.231767Z","caller":"traceutil/trace.go:171","msg":"trace[106188797] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1112; }","duration":"1.250765528s","start":"2026-04-13T21:13:56.980981Z","end":"2026-04-13T21:13:58.231747Z","steps":["trace[106188797] 'read index received' (duration: 593.369971ms)","trace[106188797] 'applied index is now lower than readState.Index' (duration: 657.393727ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:58.231891Z","caller":"traceutil/trace.go:171","msg":"trace[598140042] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"1.603804929s","start":"2026-04-13T21:13:56.628045Z","end":"2026-04-13T21:13:58.23185Z","steps":["trace[598140042] 'process raft request' (duration: 1.060926761s)","trace[598140042] 'compare' (duration: 542.016444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:58.231952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:56.628014Z","time spent":"1.603906301s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:58.232316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.167579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:13:58.23232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.896733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:13:58.232428Z","caller":"traceutil/trace.go:171","msg":"trace[456521632] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1071; }","duration":"251.388655ms","start":"2026-04-13T21:13:57.981015Z","end":"2026-04-13T21:13:58.232404Z","steps":["trace[456521632] 'agreement among raft nodes before linearized reading' (duration: 251.155269ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:58.232439Z","caller":"traceutil/trace.go:171","msg":"trace[636025601] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1071; }","duration":"425.078008ms","start":"2026-04-13T21:13:57.807337Z","end":"2026-04-13T21:13:58.232415Z","steps":["trace[636025601] 'agreement among raft nodes before linearized reading' (duration: 424.852001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:58.232326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251321004s","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-13T21:13:58.232491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:57.807312Z","time spent":"425.168811ms","remote":"127.0.0.1:47346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:13:58.232315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.46462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:58.232513Z","caller":"traceutil/trace.go:171","msg":"trace[1310773292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"1.251566471s","start":"2026-04-13T21:13:56.980925Z","end":"2026-04-13T21:13:58.232492Z","steps":["trace[1310773292] 'agreement among raft nodes before linearized reading' (duration: 1.25117874s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:58.232581Z","caller":"traceutil/trace.go:171","msg":"trace[786922386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"769.82865ms","start":"2026-04-13T21:13:57.462732Z","end":"2026-04-13T21:13:58.232561Z","steps":["trace[786922386] 'agreement among raft nodes before linearized reading' (duration: 769.443549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:58.232603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:56.980892Z","time spent":"1.251701175s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:13:58.232638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:57.462689Z","time spent":"769.932502ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:13:58.662784Z","caller":"traceutil/trace.go:171","msg":"trace[1911896909] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"113.288346ms","start":"2026-04-13T21:13:58.549457Z","end":"2026-04-13T21:13:58.662745Z","steps":["trace[1911896909] 'read index received' (duration: 113.008617ms)","trace[1911896909] 'applied index is now lower than readState.Index' (duration: 277.769µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:58.66294Z","caller":"traceutil/trace.go:171","msg":"trace[1336660679] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"140.439354ms","start":"2026-04-13T21:13:58.522461Z","end":"2026-04-13T21:13:58.6629Z","steps":["trace[1336660679] 'process raft request' (duration: 140.028154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:58.663086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.603594ms","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-13T21:13:58.663127Z","caller":"traceutil/trace.go:171","msg":"trace[887695187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"113.726007ms","start":"2026-04-13T21:13:58.549388Z","end":"2026-04-13T21:13:58.663114Z","steps":["trace[887695187] 'agreement among raft nodes before linearized reading' (duration: 113.517942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:00.144659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098692917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:14:00.491585Z","caller":"traceutil/trace.go:171","msg":"trace[81132994] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"905.360938ms","start":"2026-04-13T21:13:59.58619Z","end":"2026-04-13T21:14:00.491551Z","steps":["trace[81132994] 'process raft request' (duration: 905.180954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:00.491846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:59.58615Z","time spent":"905.526063ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:00.508396Z","caller":"traceutil/trace.go:171","msg":"trace[97148341] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1119; }","duration":"864.395658ms","start":"2026-04-13T21:13:59.643968Z","end":"2026-04-13T21:14:00.508364Z","steps":["trace[97148341] 'read index received' (duration: 847.479061ms)","trace[97148341] 'applied index is now lower than readState.Index' (duration: 16.915277ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:00.508438Z","caller":"traceutil/trace.go:171","msg":"trace[148163117] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"922.057769ms","start":"2026-04-13T21:13:59.586339Z","end":"2026-04-13T21:14:00.508397Z","steps":["trace[148163117] 'process raft request' (duration: 921.799042ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:00.50854Z","caller":"traceutil/trace.go:171","msg":"trace[1877553143] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"921.978776ms","start":"2026-04-13T21:13:59.586525Z","end":"2026-04-13T21:14:00.508504Z","steps":["trace[1877553143] 'process raft request' (duration: 921.785721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:00.508601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.590234ms","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-13T21:14:00.508644Z","caller":"traceutil/trace.go:171","msg":"trace[1930328870] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1077; }","duration":"864.731009ms","start":"2026-04-13T21:13:59.643898Z","end":"2026-04-13T21:14:00.508629Z","steps":["trace[1930328870] 'agreement among raft nodes before linearized reading' (duration: 864.619296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:00.508629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:59.58632Z","time spent":"922.201213ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:00.50864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.864162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:14:00.508667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:59.586483Z","time spent":"922.121441ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:00.508688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:59.643843Z","time spent":"864.83296ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:14:00.508702Z","caller":"traceutil/trace.go:171","msg":"trace[1439827469] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1077; }","duration":"266.935304ms","start":"2026-04-13T21:14:00.24175Z","end":"2026-04-13T21:14:00.508685Z","steps":["trace[1439827469] 'agreement among raft nodes before linearized reading' (duration: 266.820401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:00.508829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.339234ms","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-13T21:14:00.508923Z","caller":"traceutil/trace.go:171","msg":"trace[2082405167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"226.489919ms","start":"2026-04-13T21:14:00.282411Z","end":"2026-04-13T21:14:00.508901Z","steps":["trace[2082405167] 'agreement among raft nodes before linearized reading' (duration: 226.254052ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:01.167858Z","caller":"traceutil/trace.go:171","msg":"trace[436065853] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"135.043506ms","start":"2026-04-13T21:14:01.032772Z","end":"2026-04-13T21:14:01.167816Z","steps":["trace[436065853] 'process raft request' (duration: 123.233069ms)","trace[436065853] 'compare' (duration: 11.643182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:02.615947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.234573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:14:02.61599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.433186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:14:02.616111Z","caller":"traceutil/trace.go:171","msg":"trace[551284603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"102.629612ms","start":"2026-04-13T21:14:02.513453Z","end":"2026-04-13T21:14:02.616083Z","steps":["trace[551284603] 'range keys from in-memory index tree' (duration: 102.169439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:02.615998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.138585ms","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-13T21:14:02.616231Z","caller":"traceutil/trace.go:171","msg":"trace[550851266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"120.411222ms","start":"2026-04-13T21:14:02.495802Z","end":"2026-04-13T21:14:02.616213Z","steps":["trace[550851266] 'range keys from in-memory index tree' (duration: 119.673492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:02.61624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.601571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:14:02.616381Z","caller":"traceutil/trace.go:171","msg":"trace[758238746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"102.812626ms","start":"2026-04-13T21:14:02.513538Z","end":"2026-04-13T21:14:02.616351Z","steps":["trace[758238746] 'range keys from in-memory index tree' (duration: 102.332533ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:02.616114Z","caller":"traceutil/trace.go:171","msg":"trace[325580851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"155.45756ms","start":"2026-04-13T21:14:02.460608Z","end":"2026-04-13T21:14:02.616065Z","steps":["trace[325580851] 'range keys from in-memory index tree' (duration: 155.087429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:11.165173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.08209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:14:11.165422Z","caller":"traceutil/trace.go:171","msg":"trace[2085704653] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:1113; }","duration":"171.354217ms","start":"2026-04-13T21:14:10.994038Z","end":"2026-04-13T21:14:11.165392Z","steps":["trace[2085704653] 'range keys from in-memory index tree' (duration: 170.833873ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:11.45422Z","caller":"traceutil/trace.go:171","msg":"trace[1606938103] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"191.38105ms","start":"2026-04-13T21:14:11.262807Z","end":"2026-04-13T21:14:11.454188Z","steps":["trace[1606938103] 'process raft request' (duration: 113.681036ms)","trace[1606938103] 'compare' (duration: 77.564861ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:17.214879Z","caller":"traceutil/trace.go:171","msg":"trace[1326042779] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"435.67099ms","start":"2026-04-13T21:14:16.779178Z","end":"2026-04-13T21:14:17.214849Z","steps":["trace[1326042779] 'read index received' (duration: 435.501116ms)","trace[1326042779] 'applied index is now lower than readState.Index' (duration: 168.994µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:17.214935Z","caller":"traceutil/trace.go:171","msg":"trace[445004407] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"439.008712ms","start":"2026-04-13T21:14:16.775889Z","end":"2026-04-13T21:14:17.214897Z","steps":["trace[445004407] 'process raft request' (duration: 438.758775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.215129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.77583Z","time spent":"439.190477ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:17.215175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.969888ms","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-13T21:14:17.215205Z","caller":"traceutil/trace.go:171","msg":"trace[1812778937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"436.157403ms","start":"2026-04-13T21:14:16.779039Z","end":"2026-04-13T21:14:17.215196Z","steps":["trace[1812778937] 'agreement among raft nodes before linearized reading' (duration: 435.945317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.21523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.779012Z","time spent":"436.212425ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:14:17.218259Z","caller":"traceutil/trace.go:171","msg":"trace[1598621020] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"436.532073ms","start":"2026-04-13T21:14:16.781704Z","end":"2026-04-13T21:14:17.218236Z","steps":["trace[1598621020] 'process raft request' (duration: 436.134162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.218363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.781683Z","time spent":"436.630777ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:17.21852Z","caller":"traceutil/trace.go:171","msg":"trace[293856638] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"353.487613ms","start":"2026-04-13T21:14:16.86499Z","end":"2026-04-13T21:14:17.218478Z","steps":["trace[293856638] 'process raft request' (duration: 353.150983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.218598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.135253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:14:17.218688Z","caller":"traceutil/trace.go:171","msg":"trace[1516856927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"432.273556ms","start":"2026-04-13T21:14:16.786396Z","end":"2026-04-13T21:14:17.218669Z","steps":["trace[1516856927] 'agreement among raft nodes before linearized reading' (duration: 431.907696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.218735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.786382Z","time spent":"432.343208ms","remote":"127.0.0.1:47414","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-13T21:14:17.218741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.864955Z","time spent":"353.670777ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:17.218806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.14209ms","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-13T21:14:17.218854Z","caller":"traceutil/trace.go:171","msg":"trace[640267340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"346.228952ms","start":"2026-04-13T21:14:16.872611Z","end":"2026-04-13T21:14:17.21884Z","steps":["trace[640267340] 'agreement among raft nodes before linearized reading' (duration: 345.999205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:17.218883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:16.872598Z","time spent":"346.279525ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T21:14:19.292009Z","caller":"traceutil/trace.go:171","msg":"trace[1517392431] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"171.399599ms","start":"2026-04-13T21:14:19.120582Z","end":"2026-04-13T21:14:19.291982Z","steps":["trace[1517392431] 'read index received' (duration: 171.262885ms)","trace[1517392431] 'applied index is now lower than readState.Index' (duration: 135.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:19.292121Z","caller":"traceutil/trace.go:171","msg":"trace[2003041594] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"481.219887ms","start":"2026-04-13T21:14:18.810893Z","end":"2026-04-13T21:14:19.292113Z","steps":["trace[2003041594] 'process raft request' (duration: 480.896828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.292221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:18.810864Z","time spent":"481.278398ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:19.292334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:14:19.292378Z","caller":"traceutil/trace.go:171","msg":"trace[594004395] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1195; }","duration":"171.838921ms","start":"2026-04-13T21:14:19.120527Z","end":"2026-04-13T21:14:19.292366Z","steps":["trace[594004395] 'agreement among raft nodes before linearized reading' (duration: 171.762879ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:19.611477Z","caller":"traceutil/trace.go:171","msg":"trace[1562882716] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"319.358371ms","start":"2026-04-13T21:14:19.292082Z","end":"2026-04-13T21:14:19.61144Z","steps":["trace[1562882716] 'read index received' (duration: 263.85556ms)","trace[1562882716] 'applied index is now lower than readState.Index' (duration: 55.500981ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:19.611702Z","caller":"traceutil/trace.go:171","msg":"trace[417893481] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"387.198822ms","start":"2026-04-13T21:14:19.2244Z","end":"2026-04-13T21:14:19.611599Z","steps":["trace[417893481] 'process raft request' (duration: 331.614158ms)","trace[417893481] 'compare' (duration: 55.096571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:19.611737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.260626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T21:14:19.611814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.283552ms","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-13T21:14:19.611869Z","caller":"traceutil/trace.go:171","msg":"trace[1492746904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"361.448933ms","start":"2026-04-13T21:14:19.250385Z","end":"2026-04-13T21:14:19.611834Z","steps":["trace[1492746904] 'agreement among raft nodes before linearized reading' (duration: 361.065502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.611876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.630925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T21:14:19.611878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.224374Z","time spent":"387.406738ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:19.611954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.650466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:14:19.611954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.250361Z","time spent":"361.576146ms","remote":"127.0.0.1:47414","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-13T21:14:19.611968Z","caller":"traceutil/trace.go:171","msg":"trace[1776455084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"391.740538ms","start":"2026-04-13T21:14:19.220208Z","end":"2026-04-13T21:14:19.611949Z","steps":["trace[1776455084] 'agreement among raft nodes before linearized reading' (duration: 391.48278ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:19.611985Z","caller":"traceutil/trace.go:171","msg":"trace[585938691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"150.732179ms","start":"2026-04-13T21:14:19.461244Z","end":"2026-04-13T21:14:19.611976Z","steps":["trace[585938691] 'agreement among raft nodes before linearized reading' (duration: 150.684988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.612013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.220115Z","time spent":"391.890632ms","remote":"127.0.0.1:47414","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-13T21:14:19.612156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.677939ms","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-13T21:14:19.612174Z","caller":"traceutil/trace.go:171","msg":"trace[752438780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"386.719559ms","start":"2026-04-13T21:14:19.225448Z","end":"2026-04-13T21:14:19.612168Z","steps":["trace[752438780] 'agreement among raft nodes before linearized reading' (duration: 386.662927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.612195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.225438Z","time spent":"386.75425ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T21:14:19.612243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.771861ms","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-13T21:14:19.612295Z","caller":"traceutil/trace.go:171","msg":"trace[1331482640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"361.833903ms","start":"2026-04-13T21:14:19.250447Z","end":"2026-04-13T21:14:19.612281Z","steps":["trace[1331482640] 'agreement among raft nodes before linearized reading' (duration: 361.69397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.612338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.250442Z","time spent":"361.884924ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T21:14:19.611896Z","caller":"traceutil/trace.go:171","msg":"trace[199912486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1196; }","duration":"380.434827ms","start":"2026-04-13T21:14:19.231428Z","end":"2026-04-13T21:14:19.611862Z","steps":["trace[199912486] 'agreement among raft nodes before linearized reading' (duration: 380.18488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:19.61248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.231406Z","time spent":"381.064614ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:14:20.141853Z","caller":"traceutil/trace.go:171","msg":"trace[888900643] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"525.287873ms","start":"2026-04-13T21:14:19.616526Z","end":"2026-04-13T21:14:20.141814Z","steps":["trace[888900643] 'process raft request' (duration: 472.241589ms)","trace[888900643] 'compare' (duration: 52.900739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:20.142123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.616512Z","time spent":"525.476827ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:20.145321Z","caller":"traceutil/trace.go:171","msg":"trace[25920388] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"527.084232ms","start":"2026-04-13T21:14:19.618212Z","end":"2026-04-13T21:14:20.145296Z","steps":["trace[25920388] 'process raft request' (duration: 527.039951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:20.14559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.618205Z","time spent":"527.209175ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:20.145935Z","caller":"traceutil/trace.go:171","msg":"trace[1563146779] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"529.081657ms","start":"2026-04-13T21:14:19.616843Z","end":"2026-04-13T21:14:20.145925Z","steps":["trace[1563146779] 'process raft request' (duration: 528.223783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:20.145992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.616817Z","time spent":"529.147259ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:20.146132Z","caller":"traceutil/trace.go:171","msg":"trace[199364987] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"529.191711ms","start":"2026-04-13T21:14:19.61693Z","end":"2026-04-13T21:14:20.146122Z","steps":["trace[199364987] 'process raft request' (duration: 528.253745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:20.14618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.616924Z","time spent":"529.233131ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:20.146255Z","caller":"traceutil/trace.go:171","msg":"trace[1563448583] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"528.899712ms","start":"2026-04-13T21:14:19.617347Z","end":"2026-04-13T21:14:20.146246Z","steps":["trace[1563448583] 'process raft request' (duration: 527.874753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:20.146312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:19.617335Z","time spent":"528.942983ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:30.489257Z","caller":"traceutil/trace.go:171","msg":"trace[2081914905] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"194.534708ms","start":"2026-04-13T21:14:30.294688Z","end":"2026-04-13T21:14:30.489223Z","steps":["trace[2081914905] 'process raft request' (duration: 192.251705ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:31.88882Z","caller":"traceutil/trace.go:171","msg":"trace[1228865088] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"233.970464ms","start":"2026-04-13T21:14:31.654823Z","end":"2026-04-13T21:14:31.888793Z","steps":["trace[1228865088] 'process raft request' (duration: 233.81783ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:33.346905Z","caller":"traceutil/trace.go:171","msg":"trace[566411705] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"235.739712ms","start":"2026-04-13T21:14:33.111135Z","end":"2026-04-13T21:14:33.346875Z","steps":["trace[566411705] 'process raft request' (duration: 235.559948ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:36.574199Z","caller":"traceutil/trace.go:171","msg":"trace[1033317120] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"113.721893ms","start":"2026-04-13T21:14:36.460443Z","end":"2026-04-13T21:14:36.574165Z","steps":["trace[1033317120] 'read index received' (duration: 113.468677ms)","trace[1033317120] 'applied index is now lower than readState.Index' (duration: 251.226µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:36.574407Z","caller":"traceutil/trace.go:171","msg":"trace[2147038412] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"168.823626ms","start":"2026-04-13T21:14:36.405568Z","end":"2026-04-13T21:14:36.574391Z","steps":["trace[2147038412] 'process raft request' (duration: 168.377954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:36.574991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.511375ms","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-13T21:14:36.575082Z","caller":"traceutil/trace.go:171","msg":"trace[1945241398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"114.677209ms","start":"2026-04-13T21:14:36.460386Z","end":"2026-04-13T21:14:36.575063Z","steps":["trace[1945241398] 'agreement among raft nodes before linearized reading' (duration: 114.374621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:37.121127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.599773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:14:37.121326Z","caller":"traceutil/trace.go:171","msg":"trace[1490317170] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"540.630172ms","start":"2026-04-13T21:14:36.580681Z","end":"2026-04-13T21:14:37.121311Z","steps":["trace[1490317170] 'process raft request' (duration: 540.58816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:37.121384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:36.580649Z","time spent":"540.710153ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:37.121609Z","caller":"traceutil/trace.go:171","msg":"trace[864788657] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"541.374292ms","start":"2026-04-13T21:14:36.580226Z","end":"2026-04-13T21:14:37.121601Z","steps":["trace[864788657] 'process raft request' (duration: 185.207203ms)","trace[864788657] 'compare' (duration: 355.47605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:37.12165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:36.580206Z","time spent":"541.424594ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:37.12178Z","caller":"traceutil/trace.go:171","msg":"trace[953617409] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"541.464094ms","start":"2026-04-13T21:14:36.580249Z","end":"2026-04-13T21:14:37.121713Z","steps":["trace[953617409] 'process raft request' (duration: 540.96638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:37.121996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:36.580223Z","time spent":"541.650209ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:38.761963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098693345,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:14:38.877783Z","caller":"traceutil/trace.go:171","msg":"trace[1162936695] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"906.413911ms","start":"2026-04-13T21:14:37.971339Z","end":"2026-04-13T21:14:38.877753Z","steps":["trace[1162936695] 'process raft request' (duration: 906.240147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:38.878061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:37.9713Z","time spent":"906.670689ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:38.878277Z","caller":"traceutil/trace.go:171","msg":"trace[37394806] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1327; }","duration":"617.195161ms","start":"2026-04-13T21:14:38.26107Z","end":"2026-04-13T21:14:38.878265Z","steps":["trace[37394806] 'read index received' (duration: 617.190051ms)","trace[37394806] 'applied index is now lower than readState.Index' (duration: 3.97µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:38.878413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.148672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:14:38.878498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.421207ms","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-13T21:14:38.878557Z","caller":"traceutil/trace.go:171","msg":"trace[726644587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"617.540831ms","start":"2026-04-13T21:14:38.261005Z","end":"2026-04-13T21:14:38.878546Z","steps":["trace[726644587] 'agreement among raft nodes before linearized reading' (duration: 617.389676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:38.878581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:38.260977Z","time spent":"617.599972ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T21:14:38.878535Z","caller":"traceutil/trace.go:171","msg":"trace[799669822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"417.304136ms","start":"2026-04-13T21:14:38.461211Z","end":"2026-04-13T21:14:38.878515Z","steps":["trace[799669822] 'agreement among raft nodes before linearized reading' (duration: 417.159042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:38.880279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:38.461184Z","time spent":"419.068025ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:14:38.878746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.222651ms","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-13T21:14:38.88083Z","caller":"traceutil/trace.go:171","msg":"trace[283590536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"302.33622ms","start":"2026-04-13T21:14:38.578477Z","end":"2026-04-13T21:14:38.880813Z","steps":["trace[283590536] 'agreement among raft nodes before linearized reading' (duration: 300.209892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:38.881011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:38.578454Z","time spent":"302.539835ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:14:39.014629Z","caller":"traceutil/trace.go:171","msg":"trace[1842717398] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"130.429579ms","start":"2026-04-13T21:14:38.884172Z","end":"2026-04-13T21:14:39.014601Z","steps":["trace[1842717398] 'process raft request' (duration: 99.107374ms)","trace[1842717398] 'compare' (duration: 31.176632ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:39.064657Z","caller":"traceutil/trace.go:171","msg":"trace[1208105379] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"179.800777ms","start":"2026-04-13T21:14:38.88483Z","end":"2026-04-13T21:14:39.064631Z","steps":["trace[1208105379] 'process raft request' (duration: 179.656732ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:40.079155Z","caller":"traceutil/trace.go:171","msg":"trace[601285608] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"328.292697ms","start":"2026-04-13T21:14:39.750813Z","end":"2026-04-13T21:14:40.079106Z","steps":["trace[601285608] 'process raft request' (duration: 252.947192ms)","trace[601285608] 'compare' (duration: 74.41727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:40.079635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:39.750779Z","time spent":"328.481813ms","remote":"127.0.0.1:47192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":840,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:14:41.045278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.287023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:41.045346Z","caller":"traceutil/trace.go:171","msg":"trace[2089028240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"584.400105ms","start":"2026-04-13T21:14:40.460928Z","end":"2026-04-13T21:14:41.045328Z","steps":["trace[2089028240] 'range keys from in-memory index tree' (duration: 583.663566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.045372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:40.460901Z","time spent":"584.465687ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:14:41.045522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.693775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:14:41.045539Z","caller":"traceutil/trace.go:171","msg":"trace[1766084330] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1282; }","duration":"267.739855ms","start":"2026-04-13T21:14:40.777792Z","end":"2026-04-13T21:14:41.045532Z","steps":["trace[1766084330] 'count revisions from in-memory index tree' (duration: 267.636633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.045756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.170481ms","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-13T21:14:41.045886Z","caller":"traceutil/trace.go:171","msg":"trace[1588947262] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1282; }","duration":"333.345365ms","start":"2026-04-13T21:14:40.712528Z","end":"2026-04-13T21:14:41.045874Z","steps":["trace[1588947262] 'count revisions from in-memory index tree' (duration: 333.069609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.045916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:40.712503Z","time spent":"333.401978ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:14:41.149818Z","caller":"traceutil/trace.go:171","msg":"trace[1180844852] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"153.655022ms","start":"2026-04-13T21:14:40.996142Z","end":"2026-04-13T21:14:41.149797Z","steps":["trace[1180844852] 'read index received' (duration: 153.593431ms)","trace[1180844852] 'applied index is now lower than readState.Index' (duration: 60.841µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:41.149937Z","caller":"traceutil/trace.go:171","msg":"trace[786995580] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"265.73408ms","start":"2026-04-13T21:14:40.884152Z","end":"2026-04-13T21:14:41.149886Z","steps":["trace[786995580] 'process raft request' (duration: 265.335679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.150095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.92ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:14:41.150304Z","caller":"traceutil/trace.go:171","msg":"trace[1581289470] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:1283; }","duration":"154.198447ms","start":"2026-04-13T21:14:40.996072Z","end":"2026-04-13T21:14:41.150271Z","steps":["trace[1581289470] 'agreement among raft nodes before linearized reading' (duration: 153.865828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.150658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.255198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:41.150712Z","caller":"traceutil/trace.go:171","msg":"trace[1276108348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"103.411171ms","start":"2026-04-13T21:14:41.047289Z","end":"2026-04-13T21:14:41.1507Z","steps":["trace[1276108348] 'agreement among raft nodes before linearized reading' (duration: 103.246626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.15137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.877705ms","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-13T21:14:41.151437Z","caller":"traceutil/trace.go:171","msg":"trace[1565354226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"132.998048ms","start":"2026-04-13T21:14:41.018425Z","end":"2026-04-13T21:14:41.151423Z","steps":["trace[1565354226] 'agreement among raft nodes before linearized reading' (duration: 131.837807ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:41.489349Z","caller":"traceutil/trace.go:171","msg":"trace[654006437] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1338; }","duration":"227.206039ms","start":"2026-04-13T21:14:41.262127Z","end":"2026-04-13T21:14:41.489333Z","steps":["trace[654006437] 'read index received' (duration: 189.815769ms)","trace[654006437] 'applied index is now lower than readState.Index' (duration: 37.38915ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:41.489449Z","caller":"traceutil/trace.go:171","msg":"trace[205232146] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"333.254082ms","start":"2026-04-13T21:14:41.156186Z","end":"2026-04-13T21:14:41.48944Z","steps":["trace[205232146] 'process raft request' (duration: 332.914663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.489532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:41.15617Z","time spent":"333.296443ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:41.489687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:41.15195Z","time spent":"337.727624ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T21:14:41.489815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.051991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:14:41.489849Z","caller":"traceutil/trace.go:171","msg":"trace[802080194] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1285; }","duration":"225.104891ms","start":"2026-04-13T21:14:41.264735Z","end":"2026-04-13T21:14:41.48984Z","steps":["trace[802080194] 'agreement among raft nodes before linearized reading' (duration: 225.040169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.489882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.68824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T21:14:41.489937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.786775ms","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-13T21:14:41.490026Z","caller":"traceutil/trace.go:171","msg":"trace[786375271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"227.949389ms","start":"2026-04-13T21:14:41.262052Z","end":"2026-04-13T21:14:41.490001Z","steps":["trace[786375271] 'agreement among raft nodes before linearized reading' (duration: 227.668282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.490065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.683931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-13T21:14:41.490089Z","caller":"traceutil/trace.go:171","msg":"trace[74327108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"209.749023ms","start":"2026-04-13T21:14:41.28033Z","end":"2026-04-13T21:14:41.490079Z","steps":["trace[74327108] 'agreement among raft nodes before linearized reading' (duration: 209.6559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.490129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.780403ms","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-13T21:14:41.490199Z","caller":"traceutil/trace.go:171","msg":"trace[375363300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"209.861426ms","start":"2026-04-13T21:14:41.280302Z","end":"2026-04-13T21:14:41.490164Z","steps":["trace[375363300] 'agreement among raft nodes before linearized reading' (duration: 209.745133ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:41.490216Z","caller":"traceutil/trace.go:171","msg":"trace[168517049] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"333.892209ms","start":"2026-04-13T21:14:41.156278Z","end":"2026-04-13T21:14:41.49017Z","steps":["trace[168517049] 'process raft request' (duration: 333.030976ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:41.48997Z","caller":"traceutil/trace.go:171","msg":"trace[1082269962] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1285; }","duration":"150.845406ms","start":"2026-04-13T21:14:41.339103Z","end":"2026-04-13T21:14:41.489948Z","steps":["trace[1082269962] 'agreement among raft nodes before linearized reading' (duration: 150.698722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:41.490355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:41.156248Z","time spent":"334.008514ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:43.728316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164394608098693383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:14:43.732763Z","caller":"traceutil/trace.go:171","msg":"trace[1932560319] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"575.424651ms","start":"2026-04-13T21:14:43.15731Z","end":"2026-04-13T21:14:43.732735Z","steps":["trace[1932560319] 'process raft request' (duration: 575.246906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.732913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.157281Z","time spent":"575.532264ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:43.907365Z","caller":"traceutil/trace.go:171","msg":"trace[2020680803] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"679.663025ms","start":"2026-04-13T21:14:43.227662Z","end":"2026-04-13T21:14:43.907325Z","steps":["trace[2020680803] 'read index received' (duration: 504.884926ms)","trace[2020680803] 'applied index is now lower than readState.Index' (duration: 174.776079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:43.907561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.928412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:43.907512Z","caller":"traceutil/trace.go:171","msg":"trace[935099451] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"193.060748ms","start":"2026-04-13T21:14:43.714403Z","end":"2026-04-13T21:14:43.907464Z","steps":["trace[935099451] 'process raft request' (duration: 153.899189ms)","trace[935099451] 'compare' (duration: 38.738917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:43.907634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.473186ms","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:7"} {"level":"info","ts":"2026-04-13T21:14:43.90766Z","caller":"traceutil/trace.go:171","msg":"trace[1887143048] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1291; }","duration":"559.494706ms","start":"2026-04-13T21:14:43.348155Z","end":"2026-04-13T21:14:43.90765Z","steps":["trace[1887143048] 'agreement among raft nodes before linearized reading' (duration: 559.456285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.90769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.348076Z","time spent":"559.6048ms","remote":"127.0.0.1:57346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:14:43.907954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.431273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:14:43.907973Z","caller":"traceutil/trace.go:171","msg":"trace[1385490560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"375.500525ms","start":"2026-04-13T21:14:43.532467Z","end":"2026-04-13T21:14:43.907968Z","steps":["trace[1385490560] 'agreement among raft nodes before linearized reading' (duration: 375.390172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.907994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.532441Z","time spent":"375.549018ms","remote":"127.0.0.1:47414","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-13T21:14:43.90759Z","caller":"traceutil/trace.go:171","msg":"trace[581908644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1291; }","duration":"680.026486ms","start":"2026-04-13T21:14:43.227553Z","end":"2026-04-13T21:14:43.907579Z","steps":["trace[581908644] 'agreement among raft nodes before linearized reading' (duration: 679.915833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.908996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.227528Z","time spent":"681.439364ms","remote":"127.0.0.1:57548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:14:43.908126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.126695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:43.909237Z","caller":"traceutil/trace.go:171","msg":"trace[1527142170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"487.305447ms","start":"2026-04-13T21:14:43.421898Z","end":"2026-04-13T21:14:43.909203Z","steps":["trace[1527142170] 'agreement among raft nodes before linearized reading' (duration: 486.118735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.909285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.816229ms","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-13T21:14:43.909372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.421865Z","time spent":"487.45062ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:14:43.909161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.181294ms","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-13T21:14:43.909462Z","caller":"traceutil/trace.go:171","msg":"trace[1229033439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"414.248972ms","start":"2026-04-13T21:14:43.49519Z","end":"2026-04-13T21:14:43.909439Z","steps":["trace[1229033439] 'agreement among raft nodes before linearized reading' (duration: 413.08908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.90953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.495162Z","time spent":"414.349827ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:14:43.908157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.484543ms","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-13T21:14:43.909598Z","caller":"traceutil/trace.go:171","msg":"trace[849128282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"415.013743ms","start":"2026-04-13T21:14:43.494575Z","end":"2026-04-13T21:14:43.909588Z","steps":["trace[849128282] 'agreement among raft nodes before linearized reading' (duration: 413.369298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.909634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.494502Z","time spent":"415.122278ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:14:43.908223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.773788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:43.909708Z","caller":"traceutil/trace.go:171","msg":"trace[1370459018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"449.31055ms","start":"2026-04-13T21:14:43.46039Z","end":"2026-04-13T21:14:43.909701Z","steps":["trace[1370459018] 'agreement among raft nodes before linearized reading' (duration: 447.807969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.909729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.46036Z","time spent":"449.361331ms","remote":"127.0.0.1:47092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:14:43.908279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.540914ms","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-13T21:14:43.909843Z","caller":"traceutil/trace.go:171","msg":"trace[1646609843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"378.122777ms","start":"2026-04-13T21:14:43.531713Z","end":"2026-04-13T21:14:43.909836Z","steps":["trace[1646609843] 'agreement among raft nodes before linearized reading' (duration: 376.500793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.909867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.531706Z","time spent":"378.153438ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T21:14:43.910434Z","caller":"traceutil/trace.go:171","msg":"trace[1100922379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"377.969133ms","start":"2026-04-13T21:14:43.531422Z","end":"2026-04-13T21:14:43.909391Z","steps":["trace[1100922379] 'agreement among raft nodes before linearized reading' (duration: 376.847872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:43.910532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.531407Z","time spent":"379.099473ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:14:44.439248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.634265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:14:44.439356Z","caller":"traceutil/trace.go:171","msg":"trace[1373000334] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"525.696684ms","start":"2026-04-13T21:14:43.913641Z","end":"2026-04-13T21:14:44.439338Z","steps":["trace[1373000334] 'process raft request' (duration: 118.865263ms)","trace[1373000334] 'compare' (duration: 406.521573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:44.439646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.913612Z","time spent":"525.767866ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:44.443412Z","caller":"traceutil/trace.go:171","msg":"trace[1838258217] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"529.064847ms","start":"2026-04-13T21:14:43.914331Z","end":"2026-04-13T21:14:44.443396Z","steps":["trace[1838258217] 'process raft request' (duration: 528.986624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:44.443564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.914317Z","time spent":"529.19839ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:14:44.443645Z","caller":"traceutil/trace.go:171","msg":"trace[1745528865] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"529.249342ms","start":"2026-04-13T21:14:43.914325Z","end":"2026-04-13T21:14:44.443574Z","steps":["trace[1745528865] 'process raft request' (duration: 528.870981ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:44.443693Z","caller":"traceutil/trace.go:171","msg":"trace[1037274714] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"528.847471ms","start":"2026-04-13T21:14:43.914824Z","end":"2026-04-13T21:14:44.443672Z","steps":["trace[1037274714] 'process raft request' (duration: 528.542362ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:44.443789Z","caller":"traceutil/trace.go:171","msg":"trace[1447315162] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"527.782851ms","start":"2026-04-13T21:14:43.91599Z","end":"2026-04-13T21:14:44.443773Z","steps":["trace[1447315162] 'process raft request' (duration: 527.545874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:44.443819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.914812Z","time spent":"528.933142ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:44.443856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.914298Z","time spent":"529.443926ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:44.443902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:14:43.915979Z","time spent":"527.857563ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:14:46.380657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.224429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T21:14:46.387821Z","caller":"traceutil/trace.go:171","msg":"trace[1907350041] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"139.732803ms","start":"2026-04-13T21:14:46.248059Z","end":"2026-04-13T21:14:46.387792Z","steps":["trace[1907350041] 'process raft request' (duration: 139.563138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:50.745032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.939334ms","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-13T21:14:50.745115Z","caller":"traceutil/trace.go:171","msg":"trace[914876124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"234.141349ms","start":"2026-04-13T21:14:50.510955Z","end":"2026-04-13T21:14:50.745097Z","steps":["trace[914876124] 'range keys from in-memory index tree' (duration: 233.753107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:50.745089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.982725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:50.745177Z","caller":"traceutil/trace.go:171","msg":"trace[1578105249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"285.14129ms","start":"2026-04-13T21:14:50.460015Z","end":"2026-04-13T21:14:50.745156Z","steps":["trace[1578105249] 'range keys from in-memory index tree' (duration: 284.833142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:50.745475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.572569ms","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-13T21:14:50.745547Z","caller":"traceutil/trace.go:171","msg":"trace[1164573507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"235.671771ms","start":"2026-04-13T21:14:50.509864Z","end":"2026-04-13T21:14:50.745535Z","steps":["trace[1164573507] 'range keys from in-memory index tree' (duration: 235.421153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:50.745587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.229851ms","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-13T21:14:50.745642Z","caller":"traceutil/trace.go:171","msg":"trace[181348696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"245.337844ms","start":"2026-04-13T21:14:50.50029Z","end":"2026-04-13T21:14:50.745628Z","steps":["trace[181348696] 'range keys from in-memory index tree' (duration: 245.045936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:50.745694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.605992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T21:14:50.745511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.56111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:14:50.745799Z","caller":"traceutil/trace.go:171","msg":"trace[626977479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"245.91537ms","start":"2026-04-13T21:14:50.499851Z","end":"2026-04-13T21:14:50.745767Z","steps":["trace[626977479] 'range keys from in-memory index tree' (duration: 245.355404ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:50.745731Z","caller":"traceutil/trace.go:171","msg":"trace[111855181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"245.695103ms","start":"2026-04-13T21:14:50.500029Z","end":"2026-04-13T21:14:50.745724Z","steps":["trace[111855181] 'range keys from in-memory index tree' (duration: 245.509239ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:52.898269Z","caller":"traceutil/trace.go:171","msg":"trace[1699803644] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"135.344933ms","start":"2026-04-13T21:14:52.762882Z","end":"2026-04-13T21:14:52.898227Z","steps":["trace[1699803644] 'read index received' (duration: 135.113476ms)","trace[1699803644] 'applied index is now lower than readState.Index' (duration: 230.417µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:52.898585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.674651ms","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-13T21:14:52.898691Z","caller":"traceutil/trace.go:171","msg":"trace[1478591459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1360; }","duration":"135.839357ms","start":"2026-04-13T21:14:52.762829Z","end":"2026-04-13T21:14:52.898668Z","steps":["trace[1478591459] 'agreement among raft nodes before linearized reading' (duration: 135.57004ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:52.898969Z","caller":"traceutil/trace.go:171","msg":"trace[1373739899] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"136.713761ms","start":"2026-04-13T21:14:52.761641Z","end":"2026-04-13T21:14:52.898355Z","steps":["trace[1373739899] 'process raft request' (duration: 136.374321ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:52.923306Z","caller":"traceutil/trace.go:171","msg":"trace[1301541380] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"159.943684ms","start":"2026-04-13T21:14:52.763335Z","end":"2026-04-13T21:14:52.923278Z","steps":["trace[1301541380] 'process raft request' (duration: 159.587794ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:52.923353Z","caller":"traceutil/trace.go:171","msg":"trace[23729957] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"159.868502ms","start":"2026-04-13T21:14:52.763434Z","end":"2026-04-13T21:14:52.923303Z","steps":["trace[23729957] 'process raft request' (duration: 159.758739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:52.923433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.473359ms","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-13T21:14:52.924006Z","caller":"traceutil/trace.go:171","msg":"trace[701890121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1362; }","duration":"161.057605ms","start":"2026-04-13T21:14:52.762921Z","end":"2026-04-13T21:14:52.923978Z","steps":["trace[701890121] 'agreement among raft nodes before linearized reading' (duration: 160.343805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:52.92362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.005203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-13T21:14:52.92422Z","caller":"traceutil/trace.go:171","msg":"trace[29869703] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1362; }","duration":"117.64297ms","start":"2026-04-13T21:14:52.806543Z","end":"2026-04-13T21:14:52.924186Z","steps":["trace[29869703] 'agreement among raft nodes before linearized reading' (duration: 116.887089ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:59.194845Z","caller":"traceutil/trace.go:171","msg":"trace[2115275502] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"133.110682ms","start":"2026-04-13T21:14:59.061702Z","end":"2026-04-13T21:14:59.194813Z","steps":["trace[2115275502] 'process raft request' (duration: 120.058666ms)","trace[2115275502] 'compare' (duration: 12.926453ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:16:25.724768Z","caller":"traceutil/trace.go:171","msg":"trace[1100611808] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"133.10236ms","start":"2026-04-13T21:16:25.591625Z","end":"2026-04-13T21:16:25.724727Z","steps":["trace[1100611808] 'process raft request' (duration: 79.81978ms)","trace[1100611808] 'compare' (duration: 53.098714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:16:25.724925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.10919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T21:16:25.724999Z","caller":"traceutil/trace.go:171","msg":"trace[1032809162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1829; }","duration":"100.223863ms","start":"2026-04-13T21:16:25.624758Z","end":"2026-04-13T21:16:25.724982Z","steps":["trace[1032809162] 'agreement among raft nodes before linearized reading' (duration: 99.978837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:16:25.813412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.724786ms","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":"warn","ts":"2026-04-13T21:16:25.813461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.769062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-13T21:16:25.813512Z","caller":"traceutil/trace.go:171","msg":"trace[1746857955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1830; }","duration":"187.85762ms","start":"2026-04-13T21:16:25.62563Z","end":"2026-04-13T21:16:25.813488Z","steps":["trace[1746857955] 'agreement among raft nodes before linearized reading' (duration: 187.559431ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:16:25.813571Z","caller":"traceutil/trace.go:171","msg":"trace[1868845988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"185.926346ms","start":"2026-04-13T21:16:25.627619Z","end":"2026-04-13T21:16:25.813546Z","steps":["trace[1868845988] 'agreement among raft nodes before linearized reading' (duration: 185.558946ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:16:25.813473Z","caller":"traceutil/trace.go:171","msg":"trace[1877607145] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"184.589819ms","start":"2026-04-13T21:16:25.628853Z","end":"2026-04-13T21:16:25.813443Z","steps":["trace[1877607145] 'process raft request' (duration: 184.093046ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:16:25.992116Z","caller":"traceutil/trace.go:171","msg":"trace[370116946] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"172.211568ms","start":"2026-04-13T21:16:25.819878Z","end":"2026-04-13T21:16:25.99209Z","steps":["trace[370116946] 'process raft request' (duration: 172.164777ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:16:25.992588Z","caller":"traceutil/trace.go:171","msg":"trace[388654007] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"179.457208ms","start":"2026-04-13T21:16:25.813114Z","end":"2026-04-13T21:16:25.992571Z","steps":["trace[388654007] 'read index received' (duration: 89.287511ms)","trace[388654007] 'applied index is now lower than readState.Index' (duration: 90.168387ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:16:25.992558Z","caller":"traceutil/trace.go:171","msg":"trace[1485762859] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"262.163247ms","start":"2026-04-13T21:16:25.73014Z","end":"2026-04-13T21:16:25.992303Z","steps":["trace[1485762859] 'process raft request' (duration: 172.340741ms)","trace[1485762859] 'compare' (duration: 89.339413ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:16:25.992686Z","caller":"traceutil/trace.go:171","msg":"trace[389895679] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"173.070022ms","start":"2026-04-13T21:16:25.819538Z","end":"2026-04-13T21:16:25.992608Z","steps":["trace[389895679] 'process raft request' (duration: 172.455584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:16:25.993093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.475143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:16:25.993145Z","caller":"traceutil/trace.go:171","msg":"trace[723236245] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1833; }","duration":"259.585067ms","start":"2026-04-13T21:16:25.733548Z","end":"2026-04-13T21:16:25.993133Z","steps":["trace[723236245] 'agreement among raft nodes before linearized reading' (duration: 259.087593ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:16:57.949579Z","caller":"traceutil/trace.go:171","msg":"trace[528648040] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"224.434513ms","start":"2026-04-13T21:16:57.725108Z","end":"2026-04-13T21:16:57.949542Z","steps":["trace[528648040] 'process raft request' (duration: 199.519454ms)","trace[528648040] 'compare' (duration: 24.497598ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:18:10.500904Z","caller":"traceutil/trace.go:171","msg":"trace[1407853365] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"130.035388ms","start":"2026-04-13T21:18:10.370831Z","end":"2026-04-13T21:18:10.500866Z","steps":["trace[1407853365] 'process raft request' (duration: 129.703068ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:13.453678Z","caller":"traceutil/trace.go:171","msg":"trace[2117111810] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"123.08093ms","start":"2026-04-13T21:18:13.330561Z","end":"2026-04-13T21:18:13.453642Z","steps":["trace[2117111810] 'process raft request' (duration: 122.868093ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:19.812323Z","caller":"traceutil/trace.go:171","msg":"trace[2072421410] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"100.21838ms","start":"2026-04-13T21:18:19.71206Z","end":"2026-04-13T21:18:19.812278Z","steps":["trace[2072421410] 'process raft request' (duration: 36.323493ms)","trace[2072421410] 'compare' (duration: 63.64345ms)"],"step_count":2}