{"level":"warn","ts":"2026-04-18T09:10:09.173772Z","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-18T09:10:09.173892Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-18T09:10:09.174004Z","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-18T09:10:09.17402Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-18T09:10:09.174041Z","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-18T09:10:09.175111Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-18T09:10:09.175217Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-18T09:10:09.179994Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.321041ms"} {"level":"info","ts":"2026-04-18T09:10:09.188174Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-18T09:10:09.188244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:10:09.188264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-18T09:10:09.188277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:10:09.1884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-18T09:10:09.188454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-18T09:10:09.197144Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:10:09.198295Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:10:09.200309Z","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-18T09:10:09.204703Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:10:09.208777Z","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-18T09:10:09.209109Z","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-18T09:10:09.209124Z","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-18T09:10:09.212488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:10:09.213109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-18T09:10:09.213389Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-18T09:10:09.213963Z","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-18T09:10:09.214339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:10:09.214381Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:10:09.214601Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-18T09:10:09.21462Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-18T09:10:09.489295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:10:09.489369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:10:09.489395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-18T09:10:09.489412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:10:09.48942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-18T09:10:09.489432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-18T09:10:09.489447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-18T09:10:09.490279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:10:09.492897Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:10:09.493374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:10:09.493453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:10:09.493648Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:10:09.493806Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:10:09.493834Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:10:09.4943Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:10:09.494358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T09:10:09.495324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:10:09.496174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"warn","ts":"2026-04-18T09:10:14.035315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811340440479196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:10:14.04541Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067310973s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T09:10:14.541207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562553919s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:14.541403Z","caller":"traceutil/trace.go:171","msg":"trace[1646086868] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"1.637089547s","start":"2026-04-18T09:10:12.904297Z","end":"2026-04-18T09:10:14.541387Z","steps":["trace[1646086868] 'process raft request' (duration: 1.637024695s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:14.541385Z","caller":"traceutil/trace.go:171","msg":"trace[1183929819] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"1.640146198s","start":"2026-04-18T09:10:12.901205Z","end":"2026-04-18T09:10:14.541351Z","steps":["trace[1183929819] 'process raft request' (duration: 76.899083ms)","trace[1183929819] 'compare' (duration: 1.562347204s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:14.541487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:12.904283Z","time spent":"1.637165719s","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:14.541864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:12.901181Z","time spent":"1.640291512s","remote":"127.0.0.1:36066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:15.265792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.553088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:15.265887Z","caller":"traceutil/trace.go:171","msg":"trace[1156117764] linearizableReadLoop","detail":"{readStateIndex:199; appliedIndex:196; }","duration":"1.731323134s","start":"2026-04-18T09:10:13.534531Z","end":"2026-04-18T09:10:15.265854Z","steps":["trace[1156117764] 'read index received' (duration: 511.168545ms)","trace[1156117764] 'applied index is now lower than readState.Index' (duration: 1.220153819s)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:15.266006Z","caller":"traceutil/trace.go:171","msg":"trace[1498328154] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"1.616652903s","start":"2026-04-18T09:10:13.649321Z","end":"2026-04-18T09:10:15.265974Z","steps":["trace[1498328154] 'process raft request' (duration: 1.504866011s)","trace[1498328154] 'compare' (duration: 111.444684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:15.266092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731548779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:15.266142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:13.649297Z","time spent":"1.616784557s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:15.266202Z","caller":"traceutil/trace.go:171","msg":"trace[713859195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:196; }","duration":"1.731649111s","start":"2026-04-18T09:10:13.534517Z","end":"2026-04-18T09:10:15.266166Z","steps":["trace[713859195] 'agreement among raft nodes before linearized reading' (duration: 1.731442256s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:15.266308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:13.534461Z","time spent":"1.731839735s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T09:10:15.269196Z","caller":"traceutil/trace.go:171","msg":"trace[445971442] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"718.621767ms","start":"2026-04-18T09:10:14.550558Z","end":"2026-04-18T09:10:15.269179Z","steps":["trace[445971442] 'process raft request' (duration: 718.588726ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:15.269255Z","caller":"traceutil/trace.go:171","msg":"trace[1010528043] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"829.827036ms","start":"2026-04-18T09:10:14.439414Z","end":"2026-04-18T09:10:15.269241Z","steps":["trace[1010528043] 'process raft request' (duration: 829.650922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:15.269331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:14.550545Z","time spent":"718.684209ms","remote":"127.0.0.1:36280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:15.269339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:14.439367Z","time spent":"829.938809ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:15.273758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.601708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:15.273807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.838508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:15.273825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.380609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:15.273838Z","caller":"traceutil/trace.go:171","msg":"trace[1973398854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:199; }","duration":"100.91041ms","start":"2026-04-18T09:10:15.172918Z","end":"2026-04-18T09:10:15.273828Z","steps":["trace[1973398854] 'agreement among raft nodes before linearized reading' (duration: 100.843419ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:15.273832Z","caller":"traceutil/trace.go:171","msg":"trace[1472008337] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:0; response_revision:199; }","duration":"725.709301ms","start":"2026-04-18T09:10:14.548099Z","end":"2026-04-18T09:10:15.273808Z","steps":["trace[1472008337] 'agreement among raft nodes before linearized reading' (duration: 725.575588ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:15.273856Z","caller":"traceutil/trace.go:171","msg":"trace[723714412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:199; }","duration":"357.470981ms","start":"2026-04-18T09:10:14.916377Z","end":"2026-04-18T09:10:15.273848Z","steps":["trace[723714412] 'agreement among raft nodes before linearized reading' (duration: 357.400539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:15.273888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:14.916353Z","time spent":"357.529932ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T09:10:15.273882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:14.54808Z","time spent":"725.793114ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"info","ts":"2026-04-18T09:10:15.273749Z","caller":"traceutil/trace.go:171","msg":"trace[1536761857] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"114.622148ms","start":"2026-04-18T09:10:15.159114Z","end":"2026-04-18T09:10:15.273736Z","steps":["trace[1536761857] 'process raft request' (duration: 114.426094ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:18.332819Z","caller":"traceutil/trace.go:171","msg":"trace[1682561317] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:289; }","duration":"296.911357ms","start":"2026-04-18T09:10:18.035884Z","end":"2026-04-18T09:10:18.332795Z","steps":["trace[1682561317] 'read index received' (duration: 279.853191ms)","trace[1682561317] 'applied index is now lower than readState.Index' (duration: 17.057036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:18.332962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.04807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:18.333057Z","caller":"traceutil/trace.go:171","msg":"trace[1565550705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:279; }","duration":"297.184982ms","start":"2026-04-18T09:10:18.035858Z","end":"2026-04-18T09:10:18.333043Z","steps":["trace[1565550705] 'agreement among raft nodes before linearized reading' (duration: 297.048819ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:18.333275Z","caller":"traceutil/trace.go:171","msg":"trace[54882629] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"346.758942ms","start":"2026-04-18T09:10:17.986488Z","end":"2026-04-18T09:10:18.333247Z","steps":["trace[54882629] 'process raft request' (duration: 329.236996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.333422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:17.98648Z","time spent":"346.877906ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:18.333441Z","caller":"traceutil/trace.go:171","msg":"trace[1579113848] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"345.600975ms","start":"2026-04-18T09:10:17.987829Z","end":"2026-04-18T09:10:18.33343Z","steps":["trace[1579113848] 'process raft request' (duration: 344.93762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.333575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:17.987817Z","time spent":"345.685107ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:18.515628Z","caller":"traceutil/trace.go:171","msg":"trace[760811404] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"180.901125ms","start":"2026-04-18T09:10:18.334702Z","end":"2026-04-18T09:10:18.515603Z","steps":["trace[760811404] 'read index received' (duration: 180.715001ms)","trace[760811404] 'applied index is now lower than readState.Index' (duration: 185.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:18.515786Z","caller":"traceutil/trace.go:171","msg":"trace[100006283] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"477.269809ms","start":"2026-04-18T09:10:18.038505Z","end":"2026-04-18T09:10:18.515774Z","steps":["trace[100006283] 'process raft request' (duration: 476.942232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.515942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.162902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-18T09:10:18.516029Z","caller":"traceutil/trace.go:171","msg":"trace[935986787] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:280; }","duration":"181.252033ms","start":"2026-04-18T09:10:18.33474Z","end":"2026-04-18T09:10:18.515992Z","steps":["trace[935986787] 'agreement among raft nodes before linearized reading' (duration: 181.138991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.51623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.52332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-04-18T09:10:18.516214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:18.038496Z","time spent":"477.31805ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:10:18.516268Z","caller":"traceutil/trace.go:171","msg":"trace[1665252420] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:280; }","duration":"181.591672ms","start":"2026-04-18T09:10:18.334667Z","end":"2026-04-18T09:10:18.516258Z","steps":["trace[1665252420] 'agreement among raft nodes before linearized reading' (duration: 181.52633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.516397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.554148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T09:10:18.516429Z","caller":"traceutil/trace.go:171","msg":"trace[1207018040] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:280; }","duration":"180.603398ms","start":"2026-04-18T09:10:18.335818Z","end":"2026-04-18T09:10:18.516421Z","steps":["trace[1207018040] 'agreement among raft nodes before linearized reading' (duration: 180.552567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:18.847672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.681784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:18.847779Z","caller":"traceutil/trace.go:171","msg":"trace[701458598] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"327.442264ms","start":"2026-04-18T09:10:18.520324Z","end":"2026-04-18T09:10:18.847766Z","steps":["trace[701458598] 'read index received' (duration: 214.643648ms)","trace[701458598] 'applied index is now lower than readState.Index' (duration: 112.797726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:18.847881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.546256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-18T09:10:18.847903Z","caller":"traceutil/trace.go:171","msg":"trace[937829928] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:281; }","duration":"327.594978ms","start":"2026-04-18T09:10:18.520302Z","end":"2026-04-18T09:10:18.847897Z","steps":["trace[937829928] 'agreement among raft nodes before linearized reading' (duration: 327.499896ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:18.847882Z","caller":"traceutil/trace.go:171","msg":"trace[1324612324] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"327.775952ms","start":"2026-04-18T09:10:18.520069Z","end":"2026-04-18T09:10:18.847845Z","steps":["trace[1324612324] 'process raft request' (duration: 214.872823ms)","trace[1324612324] 'compare' (duration: 112.555891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:18.847924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:18.520294Z","time spent":"327.624879ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3997,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-18T09:10:18.848023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:18.520052Z","time spent":"327.909316ms","remote":"127.0.0.1:36172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:41.504347Z","caller":"traceutil/trace.go:171","msg":"trace[759918384] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"720.432159ms","start":"2026-04-18T09:10:40.783885Z","end":"2026-04-18T09:10:41.504317Z","steps":["trace[759918384] 'process raft request' (duration: 696.643347ms)","trace[759918384] 'compare' (duration: 23.326561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:41.504471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:40.783865Z","time spent":"720.544311ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:42.951939Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471228481s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T09:10:43.2358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999871832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-18T09:10:43.235909Z","caller":"traceutil/trace.go:171","msg":"trace[630530434] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000030856s","start":"2026-04-18T09:10:41.235851Z","end":"2026-04-18T09:10:43.235882Z","steps":["trace[630530434] 'agreement among raft nodes before linearized reading' (duration: 1.999899283s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.23596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.235835Z","time spent":"2.000114248s","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:10:43.506217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.989168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:43.507015Z","caller":"traceutil/trace.go:171","msg":"trace[1350293580] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.668688213s","start":"2026-04-18T09:10:40.838275Z","end":"2026-04-18T09:10:43.506963Z","steps":["trace[1350293580] 'process raft request' (duration: 2.113886076s)","trace[1350293580] 'compare' (duration: 553.748773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:43.507186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:40.838253Z","time spent":"2.668859988s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:43.507906Z","caller":"traceutil/trace.go:171","msg":"trace[1925687934] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.592263931s","start":"2026-04-18T09:10:40.915596Z","end":"2026-04-18T09:10:43.50786Z","steps":["trace[1925687934] 'process raft request' (duration: 2.591720118s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:43.507949Z","caller":"traceutil/trace.go:171","msg":"trace[562216628] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:569; }","duration":"2.461364426s","start":"2026-04-18T09:10:41.046528Z","end":"2026-04-18T09:10:43.507892Z","steps":["trace[562216628] 'read index received' (duration: 433.251189ms)","trace[562216628] 'applied index is now lower than readState.Index' (duration: 2.028111837s)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:43.508033Z","caller":"traceutil/trace.go:171","msg":"trace[2018961382] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"2.324825289s","start":"2026-04-18T09:10:41.183161Z","end":"2026-04-18T09:10:43.507986Z","steps":["trace[2018961382] 'process raft request' (duration: 2.324646625s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.508056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:40.915564Z","time spent":"2.592412585s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:10:43.508066Z","caller":"traceutil/trace.go:171","msg":"trace[1549931084] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"2.125421233s","start":"2026-04-18T09:10:41.382626Z","end":"2026-04-18T09:10:43.508047Z","steps":["trace[1549931084] 'process raft request' (duration: 2.125220549s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.508143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.382607Z","time spent":"2.125491205s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:43.50817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.183132Z","time spent":"2.324965111s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:43.508392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.461843837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cbpzr\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-04-18T09:10:43.508455Z","caller":"traceutil/trace.go:171","msg":"trace[1208091534] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-cbpzr; range_end:; response_count:1; response_revision:554; }","duration":"2.461944148s","start":"2026-04-18T09:10:41.046494Z","end":"2026-04-18T09:10:43.508438Z","steps":["trace[1208091534] 'agreement among raft nodes before linearized reading' (duration: 2.461805535s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.50853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.046482Z","time spent":"2.462034521s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4702,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cbpzr\" "} {"level":"info","ts":"2026-04-18T09:10:43.508858Z","caller":"traceutil/trace.go:171","msg":"trace[1944337885] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"2.525354059s","start":"2026-04-18T09:10:40.983336Z","end":"2026-04-18T09:10:43.50869Z","steps":["trace[1944337885] 'process raft request' (duration: 2.524419157s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.508983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:40.983318Z","time spent":"2.525605845s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:43.675329Z","caller":"traceutil/trace.go:171","msg":"trace[1267510171] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"2.167262454s","start":"2026-04-18T09:10:41.508032Z","end":"2026-04-18T09:10:43.675294Z","steps":["trace[1267510171] 'process raft request' (duration: 2.118546444s)","trace[1267510171] 'compare' (duration: 48.482605ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:43.675393Z","caller":"traceutil/trace.go:171","msg":"trace[44987771] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.089230454s","start":"2026-04-18T09:10:41.586139Z","end":"2026-04-18T09:10:43.67537Z","steps":["trace[44987771] 'process raft request' (duration: 2.08905998s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.675467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.508007Z","time spent":"2.167398358s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:43.675514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.586114Z","time spent":"2.089335667s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:43.675783Z","caller":"traceutil/trace.go:171","msg":"trace[214194651] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.691650783s","start":"2026-04-18T09:10:41.984118Z","end":"2026-04-18T09:10:43.675769Z","steps":["trace[214194651] 'process raft request' (duration: 1.691231913s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:43.675839Z","caller":"traceutil/trace.go:171","msg":"trace[1695488777] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.904257124s","start":"2026-04-18T09:10:41.771568Z","end":"2026-04-18T09:10:43.675825Z","steps":["trace[1695488777] 'process raft request' (duration: 1.903676111s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:43.675877Z","caller":"traceutil/trace.go:171","msg":"trace[706479659] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.88814862s","start":"2026-04-18T09:10:41.787719Z","end":"2026-04-18T09:10:43.675868Z","steps":["trace[706479659] 'process raft request' (duration: 1.887595028s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.675914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.787702Z","time spent":"1.888194632s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:43.675919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.771545Z","time spent":"1.904330766s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:10:43.675842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.98407Z","time spent":"1.691742715s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:43.676202Z","caller":"traceutil/trace.go:171","msg":"trace[1296745051] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:575; }","duration":"168.176751ms","start":"2026-04-18T09:10:43.508014Z","end":"2026-04-18T09:10:43.67619Z","steps":["trace[1296745051] 'read index received' (duration: 118.433107ms)","trace[1296745051] 'applied index is now lower than readState.Index' (duration: 49.742584ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:43.676245Z","caller":"traceutil/trace.go:171","msg":"trace[286559675] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"159.422357ms","start":"2026-04-18T09:10:43.516788Z","end":"2026-04-18T09:10:43.67621Z","steps":["trace[286559675] 'process raft request' (duration: 158.599678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.67633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.232573449s","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-18T09:10:43.676365Z","caller":"traceutil/trace.go:171","msg":"trace[509692924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"2.232640311s","start":"2026-04-18T09:10:41.443716Z","end":"2026-04-18T09:10:43.676356Z","steps":["trace[509692924] 'agreement among raft nodes before linearized reading' (duration: 2.232586769s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.67637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295133807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:43.676397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.443703Z","time spent":"2.232686471s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T09:10:43.676404Z","caller":"traceutil/trace.go:171","msg":"trace[1596154286] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"1.295206198s","start":"2026-04-18T09:10:42.381185Z","end":"2026-04-18T09:10:43.676392Z","steps":["trace[1596154286] 'agreement among raft nodes before linearized reading' (duration: 1.295166078s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.676325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288431401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T09:10:43.676436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.381169Z","time spent":"1.295258929s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-18T09:10:43.676505Z","caller":"traceutil/trace.go:171","msg":"trace[125787990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:561; }","duration":"1.288597385s","start":"2026-04-18T09:10:42.387857Z","end":"2026-04-18T09:10:43.676455Z","steps":["trace[125787990] 'agreement among raft nodes before linearized reading' (duration: 1.288346159s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.676521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495170196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:43.67657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.275887283s","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-18T09:10:43.676583Z","caller":"traceutil/trace.go:171","msg":"trace[1895779015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"1.495255578s","start":"2026-04-18T09:10:42.181318Z","end":"2026-04-18T09:10:43.676574Z","steps":["trace[1895779015] 'agreement among raft nodes before linearized reading' (duration: 1.495185666s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:43.676601Z","caller":"traceutil/trace.go:171","msg":"trace[619698379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"2.275933994s","start":"2026-04-18T09:10:41.400659Z","end":"2026-04-18T09:10:43.676593Z","steps":["trace[619698379] 'agreement among raft nodes before linearized reading' (duration: 2.275878803s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.676612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.181302Z","time spent":"1.495303829s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-18T09:10:43.676636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.400646Z","time spent":"2.275982425s","remote":"127.0.0.1:36226","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-18T09:10:43.676649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.920162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:43.676598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.387849Z","time spent":"1.288695377s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T09:10:43.676282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.280996302s","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-18T09:10:43.676769Z","caller":"traceutil/trace.go:171","msg":"trace[1887672489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"2.281513214s","start":"2026-04-18T09:10:41.395246Z","end":"2026-04-18T09:10:43.676759Z","steps":["trace[1887672489] 'agreement among raft nodes before linearized reading' (duration: 2.280989152s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.676328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.120235053s","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-18T09:10:43.676807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.39518Z","time spent":"2.281617226s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T09:10:43.676849Z","caller":"traceutil/trace.go:171","msg":"trace[214598832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"2.120783036s","start":"2026-04-18T09:10:41.55605Z","end":"2026-04-18T09:10:43.676833Z","steps":["trace[214598832] 'agreement among raft nodes before linearized reading' (duration: 2.120237393s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.676911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:41.556001Z","time spent":"2.120884158s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:10:43.676962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.847096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T09:10:43.676994Z","caller":"traceutil/trace.go:171","msg":"trace[693399178] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:561; }","duration":"782.917648ms","start":"2026-04-18T09:10:42.894066Z","end":"2026-04-18T09:10:43.676984Z","steps":["trace[693399178] 'agreement among raft nodes before linearized reading' (duration: 782.862777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.677029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.894046Z","time spent":"782.977069ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-04-18T09:10:43.677172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.48819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5577"} {"level":"warn","ts":"2026-04-18T09:10:43.677189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.222843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-18T09:10:43.677205Z","caller":"traceutil/trace.go:171","msg":"trace[1831434333] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"695.547261ms","start":"2026-04-18T09:10:42.981649Z","end":"2026-04-18T09:10:43.677197Z","steps":["trace[1831434333] 'agreement among raft nodes before linearized reading' (duration: 695.49424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.677231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.981632Z","time spent":"695.593323ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5600,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-04-18T09:10:43.677237Z","caller":"traceutil/trace.go:171","msg":"trace[961064412] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"895.313105ms","start":"2026-04-18T09:10:42.781908Z","end":"2026-04-18T09:10:43.677222Z","steps":["trace[961064412] 'agreement among raft nodes before linearized reading' (duration: 895.134101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.677269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.781889Z","time spent":"895.372506ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-18T09:10:43.677304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09517973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-18T09:10:43.677339Z","caller":"traceutil/trace.go:171","msg":"trace[392806619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"1.095211741s","start":"2026-04-18T09:10:42.582115Z","end":"2026-04-18T09:10:43.677327Z","steps":["trace[392806619] 'agreement among raft nodes before linearized reading' (duration: 1.095086328s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.677372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:42.582095Z","time spent":"1.095269082s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-04-18T09:10:43.676689Z","caller":"traceutil/trace.go:171","msg":"trace[576939641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"439.968174ms","start":"2026-04-18T09:10:43.236701Z","end":"2026-04-18T09:10:43.676669Z","steps":["trace[576939641] 'agreement among raft nodes before linearized reading' (duration: 439.922723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:43.678572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:43.236685Z","time spent":"441.823387ms","remote":"127.0.0.1:35984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:10:43.679315Z","caller":"traceutil/trace.go:171","msg":"trace[1760978806] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"132.866842ms","start":"2026-04-18T09:10:43.546433Z","end":"2026-04-18T09:10:43.6793Z","steps":["trace[1760978806] 'process raft request' (duration: 128.996112ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:48.892158Z","caller":"traceutil/trace.go:171","msg":"trace[1396404800] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"174.020226ms","start":"2026-04-18T09:10:48.718099Z","end":"2026-04-18T09:10:48.892119Z","steps":["trace[1396404800] 'process raft request' (duration: 172.108862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:49.292694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.03672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:49.292833Z","caller":"traceutil/trace.go:171","msg":"trace[1237897864] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"340.465156ms","start":"2026-04-18T09:10:48.952331Z","end":"2026-04-18T09:10:49.292796Z","steps":["trace[1237897864] 'process raft request' (duration: 93.249612ms)","trace[1237897864] 'compare' (duration: 246.827805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:49.292977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:48.952305Z","time spent":"340.59731ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:10:49.9529Z","caller":"traceutil/trace.go:171","msg":"trace[322998621] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"144.182094ms","start":"2026-04-18T09:10:49.8087Z","end":"2026-04-18T09:10:49.952882Z","steps":["trace[322998621] 'process raft request' (duration: 71.392866ms)","trace[322998621] 'compare' (duration: 72.694796ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:50.038262Z","caller":"traceutil/trace.go:171","msg":"trace[614439887] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"229.3778ms","start":"2026-04-18T09:10:49.808868Z","end":"2026-04-18T09:10:50.038246Z","steps":["trace[614439887] 'process raft request' (duration: 229.316458ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:50.03845Z","caller":"traceutil/trace.go:171","msg":"trace[1533697495] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"229.728647ms","start":"2026-04-18T09:10:49.808711Z","end":"2026-04-18T09:10:50.03844Z","steps":["trace[1533697495] 'process raft request' (duration: 229.363389ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:50.038695Z","caller":"traceutil/trace.go:171","msg":"trace[1005332718] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"229.38166ms","start":"2026-04-18T09:10:49.809294Z","end":"2026-04-18T09:10:50.038675Z","steps":["trace[1005332718] 'process raft request' (duration: 228.922899ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:50.03885Z","caller":"traceutil/trace.go:171","msg":"trace[1536780011] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:650; }","duration":"168.855986ms","start":"2026-04-18T09:10:49.869972Z","end":"2026-04-18T09:10:50.038828Z","steps":["trace[1536780011] 'read index received' (duration: 10.099755ms)","trace[1536780011] 'applied index is now lower than readState.Index' (duration: 158.755141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:50.038979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.983989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.039452Z","caller":"traceutil/trace.go:171","msg":"trace[374686401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"169.491881ms","start":"2026-04-18T09:10:49.869947Z","end":"2026-04-18T09:10:50.039438Z","steps":["trace[374686401] 'agreement among raft nodes before linearized reading' (duration: 168.952508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:50.039526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.430659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.039574Z","caller":"traceutil/trace.go:171","msg":"trace[1006378027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"169.527162ms","start":"2026-04-18T09:10:49.870038Z","end":"2026-04-18T09:10:50.039565Z","steps":["trace[1006378027] 'agreement among raft nodes before linearized reading' (duration: 169.405079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:50.039784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.207092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.039805Z","caller":"traceutil/trace.go:171","msg":"trace[450645235] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:632; }","duration":"143.240792ms","start":"2026-04-18T09:10:49.896559Z","end":"2026-04-18T09:10:50.0398Z","steps":["trace[450645235] 'agreement among raft nodes before linearized reading' (duration: 143.212241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:50.040016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.56577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.040044Z","caller":"traceutil/trace.go:171","msg":"trace[1468600875] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:632; }","duration":"143.607121ms","start":"2026-04-18T09:10:49.896431Z","end":"2026-04-18T09:10:50.040038Z","steps":["trace[1468600875] 'agreement among raft nodes before linearized reading' (duration: 143.559939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:50.040245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.083942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.040266Z","caller":"traceutil/trace.go:171","msg":"trace[92087135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:632; }","duration":"144.134193ms","start":"2026-04-18T09:10:49.896126Z","end":"2026-04-18T09:10:50.04026Z","steps":["trace[92087135] 'agreement among raft nodes before linearized reading' (duration: 144.092722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:50.040327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.558532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:50.04034Z","caller":"traceutil/trace.go:171","msg":"trace[765628478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"169.610734ms","start":"2026-04-18T09:10:49.870725Z","end":"2026-04-18T09:10:50.040336Z","steps":["trace[765628478] 'agreement among raft nodes before linearized reading' (duration: 169.590903ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:50.038897Z","caller":"traceutil/trace.go:171","msg":"trace[1376626185] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"229.445811ms","start":"2026-04-18T09:10:49.809364Z","end":"2026-04-18T09:10:50.03881Z","steps":["trace[1376626185] 'process raft request' (duration: 229.232726ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:50.038988Z","caller":"traceutil/trace.go:171","msg":"trace[274148415] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"187.935699ms","start":"2026-04-18T09:10:49.851042Z","end":"2026-04-18T09:10:50.038977Z","steps":["trace[274148415] 'process raft request' (duration: 187.709174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.032631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.761213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.032691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.997131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.032761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.523543ms","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-18T09:10:51.032774Z","caller":"traceutil/trace.go:171","msg":"trace[423249439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"229.150404ms","start":"2026-04-18T09:10:50.803597Z","end":"2026-04-18T09:10:51.032748Z","steps":["trace[423249439] 'range keys from in-memory index tree' (duration: 228.927689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.032796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.717183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.032795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.270177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.032815Z","caller":"traceutil/trace.go:171","msg":"trace[102992883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:640; }","duration":"207.579264ms","start":"2026-04-18T09:10:50.825219Z","end":"2026-04-18T09:10:51.032798Z","steps":["trace[102992883] 'range keys from in-memory index tree' (duration: 207.472531ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.032827Z","caller":"traceutil/trace.go:171","msg":"trace[60320305] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:640; }","duration":"193.761354ms","start":"2026-04-18T09:10:50.839055Z","end":"2026-04-18T09:10:51.032816Z","steps":["trace[60320305] 'range keys from in-memory index tree' (duration: 193.633331ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.03285Z","caller":"traceutil/trace.go:171","msg":"trace[1859515335] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:640; }","duration":"116.379059ms","start":"2026-04-18T09:10:50.916457Z","end":"2026-04-18T09:10:51.032836Z","steps":["trace[1859515335] 'range keys from in-memory index tree' (duration: 116.236476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.032998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.072393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.03303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.781979ms","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":"warn","ts":"2026-04-18T09:10:51.033041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.327756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033061Z","caller":"traceutil/trace.go:171","msg":"trace[856827974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:640; }","duration":"207.8239ms","start":"2026-04-18T09:10:50.825228Z","end":"2026-04-18T09:10:51.033052Z","steps":["trace[856827974] 'count revisions from in-memory index tree' (duration: 207.734918ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033073Z","caller":"traceutil/trace.go:171","msg":"trace[1977441462] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:640; }","duration":"178.367697ms","start":"2026-04-18T09:10:50.854696Z","end":"2026-04-18T09:10:51.033064Z","steps":["trace[1977441462] 'count revisions from in-memory index tree' (duration: 178.292386ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033053Z","caller":"traceutil/trace.go:171","msg":"trace[1041944639] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:640; }","duration":"160.150034ms","start":"2026-04-18T09:10:50.87289Z","end":"2026-04-18T09:10:51.03304Z","steps":["trace[1041944639] 'count revisions from in-memory index tree' (duration: 160.031371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.12627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033165Z","caller":"traceutil/trace.go:171","msg":"trace[889940649] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"334.172991ms","start":"2026-04-18T09:10:50.698982Z","end":"2026-04-18T09:10:51.033155Z","steps":["trace[889940649] 'count revisions from in-memory index tree' (duration: 334.067468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.776759ms","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":"warn","ts":"2026-04-18T09:10:51.033203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:50.698938Z","time spent":"334.255553ms","remote":"127.0.0.1:59700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-18T09:10:51.033241Z","caller":"traceutil/trace.go:171","msg":"trace[1397225798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:640; }","duration":"207.887841ms","start":"2026-04-18T09:10:50.825335Z","end":"2026-04-18T09:10:51.033223Z","steps":["trace[1397225798] 'range keys from in-memory index tree' (duration: 207.693177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.338874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033402Z","caller":"traceutil/trace.go:171","msg":"trace[1489093918] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"334.401696ms","start":"2026-04-18T09:10:50.698991Z","end":"2026-04-18T09:10:51.033393Z","steps":["trace[1489093918] 'range keys from in-memory index tree' (duration: 334.310003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.611491ms","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":"warn","ts":"2026-04-18T09:10:51.033419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.905911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033443Z","caller":"traceutil/trace.go:171","msg":"trace[1182884334] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:640; }","duration":"140.659262ms","start":"2026-04-18T09:10:50.892774Z","end":"2026-04-18T09:10:51.033433Z","steps":["trace[1182884334] 'count revisions from in-memory index tree' (duration: 140.58657ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033453Z","caller":"traceutil/trace.go:171","msg":"trace[970946705] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:640; }","duration":"100.956331ms","start":"2026-04-18T09:10:50.932487Z","end":"2026-04-18T09:10:51.033444Z","steps":["trace[970946705] 'range keys from in-memory index tree' (duration: 100.840338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.508752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033532Z","caller":"traceutil/trace.go:171","msg":"trace[1479667054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:640; }","duration":"160.569184ms","start":"2026-04-18T09:10:50.872916Z","end":"2026-04-18T09:10:51.033486Z","steps":["trace[1479667054] 'range keys from in-memory index tree' (duration: 160.463742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.608311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.033604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.084306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.033618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.753105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033645Z","caller":"traceutil/trace.go:171","msg":"trace[1782656773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"230.134328ms","start":"2026-04-18T09:10:50.803502Z","end":"2026-04-18T09:10:51.033636Z","steps":["trace[1782656773] 'range keys from in-memory index tree' (duration: 230.046616ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033651Z","caller":"traceutil/trace.go:171","msg":"trace[1037921792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:640; }","duration":"159.840607ms","start":"2026-04-18T09:10:50.873799Z","end":"2026-04-18T09:10:51.033639Z","steps":["trace[1037921792] 'range keys from in-memory index tree' (duration: 159.670493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.032762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.416789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.033676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.177852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033701Z","caller":"traceutil/trace.go:171","msg":"trace[849630044] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:640; }","duration":"195.351691ms","start":"2026-04-18T09:10:50.838329Z","end":"2026-04-18T09:10:51.033681Z","steps":["trace[849630044] 'count revisions from in-memory index tree' (duration: 194.348297ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033721Z","caller":"traceutil/trace.go:171","msg":"trace[1005905591] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:640; }","duration":"140.233553ms","start":"2026-04-18T09:10:50.893477Z","end":"2026-04-18T09:10:51.03371Z","steps":["trace[1005905591] 'range keys from in-memory index tree' (duration: 140.1468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.03378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.178678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033814Z","caller":"traceutil/trace.go:171","msg":"trace[1430441091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"230.23811ms","start":"2026-04-18T09:10:50.803569Z","end":"2026-04-18T09:10:51.033807Z","steps":["trace[1430441091] 'count revisions from in-memory index tree' (duration: 230.122537ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.03271Z","caller":"traceutil/trace.go:171","msg":"trace[1044810597] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:640; }","duration":"177.859435ms","start":"2026-04-18T09:10:50.854835Z","end":"2026-04-18T09:10:51.032695Z","steps":["trace[1044810597] 'range keys from in-memory index tree' (duration: 177.676351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.033448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:50.698973Z","time spent":"334.466707ms","remote":"127.0.0.1:59700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-18T09:10:51.033937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.641252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:51.032695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.384448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.033992Z","caller":"traceutil/trace.go:171","msg":"trace[2096971945] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:640; }","duration":"141.241516ms","start":"2026-04-18T09:10:50.892735Z","end":"2026-04-18T09:10:51.033977Z","steps":["trace[2096971945] 'range keys from in-memory index tree' (duration: 140.534359ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.034007Z","caller":"traceutil/trace.go:171","msg":"trace[1492494998] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:640; }","duration":"195.725299ms","start":"2026-04-18T09:10:50.83827Z","end":"2026-04-18T09:10:51.033996Z","steps":["trace[1492494998] 'range keys from in-memory index tree' (duration: 194.345637ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.033972Z","caller":"traceutil/trace.go:171","msg":"trace[1178227526] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:640; }","duration":"118.695703ms","start":"2026-04-18T09:10:50.915268Z","end":"2026-04-18T09:10:51.033964Z","steps":["trace[1178227526] 'range keys from in-memory index tree' (duration: 118.571191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.03353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.839449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.034145Z","caller":"traceutil/trace.go:171","msg":"trace[207937829] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:640; }","duration":"101.470663ms","start":"2026-04-18T09:10:50.932663Z","end":"2026-04-18T09:10:51.034134Z","steps":["trace[207937829] 'count revisions from in-memory index tree' (duration: 100.755687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:51.032815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.414553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:10:51.034625Z","caller":"traceutil/trace.go:171","msg":"trace[1672431870] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:640; }","duration":"119.265226ms","start":"2026-04-18T09:10:50.915338Z","end":"2026-04-18T09:10:51.034603Z","steps":["trace[1672431870] 'count revisions from in-memory index tree' (duration: 117.348632ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:51.293084Z","caller":"traceutil/trace.go:171","msg":"trace[293458262] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"207.302278ms","start":"2026-04-18T09:10:51.08575Z","end":"2026-04-18T09:10:51.293052Z","steps":["trace[293458262] 'process raft request' (duration: 206.768776ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:52.284588Z","caller":"traceutil/trace.go:171","msg":"trace[546169360] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"241.295676ms","start":"2026-04-18T09:10:52.043273Z","end":"2026-04-18T09:10:52.284569Z","steps":["trace[546169360] 'read index received' (duration: 241.0612ms)","trace[546169360] 'applied index is now lower than readState.Index' (duration: 233.596µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:52.284691Z","caller":"traceutil/trace.go:171","msg":"trace[75131471] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"324.846863ms","start":"2026-04-18T09:10:51.959836Z","end":"2026-04-18T09:10:52.284682Z","steps":["trace[75131471] 'process raft request' (duration: 324.606138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:52.284779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:51.959814Z","time spent":"324.896695ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:10:52.284946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.675802ms","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-18T09:10:52.285051Z","caller":"traceutil/trace.go:171","msg":"trace[1690093583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"240.788974ms","start":"2026-04-18T09:10:52.044241Z","end":"2026-04-18T09:10:52.28503Z","steps":["trace[1690093583] 'agreement among raft nodes before linearized reading' (duration: 240.557409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:52.285092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.659818ms","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-18T09:10:52.285117Z","caller":"traceutil/trace.go:171","msg":"trace[298876664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"239.703049ms","start":"2026-04-18T09:10:52.045407Z","end":"2026-04-18T09:10:52.28511Z","steps":["trace[298876664] 'agreement among raft nodes before linearized reading' (duration: 239.609147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:52.285156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.100018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:10:52.285225Z","caller":"traceutil/trace.go:171","msg":"trace[660916234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"240.189571ms","start":"2026-04-18T09:10:52.045019Z","end":"2026-04-18T09:10:52.285208Z","steps":["trace[660916234] 'agreement among raft nodes before linearized reading' (duration: 240.030117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:52.28551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.193757ms","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-18T09:10:52.285639Z","caller":"traceutil/trace.go:171","msg":"trace[1720575994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"242.379381ms","start":"2026-04-18T09:10:52.043231Z","end":"2026-04-18T09:10:52.28561Z","steps":["trace[1720575994] 'agreement among raft nodes before linearized reading' (duration: 241.863839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:53.647684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.530209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:10:53.647936Z","caller":"traceutil/trace.go:171","msg":"trace[950583205] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.358939336s","start":"2026-04-18T09:10:52.288965Z","end":"2026-04-18T09:10:53.647904Z","steps":["trace[950583205] 'process raft request' (duration: 817.09019ms)","trace[950583205] 'compare' (duration: 541.362965ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:10:53.648027Z","caller":"traceutil/trace.go:171","msg":"trace[615950161] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"753.087716ms","start":"2026-04-18T09:10:52.894921Z","end":"2026-04-18T09:10:53.648008Z","steps":["trace[615950161] 'read index received' (duration: 211.108807ms)","trace[615950161] 'applied index is now lower than readState.Index' (duration: 541.977919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:10:53.648045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:52.288941Z","time spent":"1.359050269s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:10:53.64808Z","caller":"traceutil/trace.go:171","msg":"trace[537059841] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.358636009s","start":"2026-04-18T09:10:52.289396Z","end":"2026-04-18T09:10:53.648032Z","steps":["trace[537059841] 'process raft request' (duration: 1.358415714s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:53.648143Z","caller":"traceutil/trace.go:171","msg":"trace[751908954] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.358714982s","start":"2026-04-18T09:10:52.289414Z","end":"2026-04-18T09:10:53.648129Z","steps":["trace[751908954] 'process raft request' (duration: 1.358469536s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:53.648226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:52.289384Z","time spent":"1.358761732s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:10:53.648269Z","caller":"traceutil/trace.go:171","msg":"trace[237522470] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.358084027s","start":"2026-04-18T09:10:52.290174Z","end":"2026-04-18T09:10:53.648258Z","steps":["trace[237522470] 'process raft request' (duration: 1.357766559s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:53.648343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:52.290166Z","time spent":"1.358134268s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:10:53.648411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.575038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:10:53.648224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:52.289405Z","time spent":"1.358769993s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:10:53.648501Z","caller":"traceutil/trace.go:171","msg":"trace[1972939234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"412.67728ms","start":"2026-04-18T09:10:53.235768Z","end":"2026-04-18T09:10:53.648445Z","steps":["trace[1972939234] 'agreement among raft nodes before linearized reading' (duration: 412.583398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:53.648627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:53.235744Z","time spent":"412.866695ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:10:53.648681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.762371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T09:10:53.648727Z","caller":"traceutil/trace.go:171","msg":"trace[530642817] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:646; }","duration":"753.849903ms","start":"2026-04-18T09:10:52.894863Z","end":"2026-04-18T09:10:53.648712Z","steps":["trace[530642817] 'agreement among raft nodes before linearized reading' (duration: 753.665489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:53.648769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:52.894837Z","time spent":"753.920474ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"info","ts":"2026-04-18T09:10:55.601728Z","caller":"traceutil/trace.go:171","msg":"trace[243425322] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"113.771149ms","start":"2026-04-18T09:10:55.487929Z","end":"2026-04-18T09:10:55.6017Z","steps":["trace[243425322] 'process raft request' (duration: 113.637476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:55.820333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.938172ms","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-18T09:10:55.820415Z","caller":"traceutil/trace.go:171","msg":"trace[1890835456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"167.051664ms","start":"2026-04-18T09:10:55.653344Z","end":"2026-04-18T09:10:55.820396Z","steps":["trace[1890835456] 'range keys from in-memory index tree' (duration: 166.744647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:55.820508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.631647ms","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-18T09:10:55.820552Z","caller":"traceutil/trace.go:171","msg":"trace[420469289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"167.693039ms","start":"2026-04-18T09:10:55.652847Z","end":"2026-04-18T09:10:55.82054Z","steps":["trace[420469289] 'range keys from in-memory index tree' (duration: 167.500974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:55.820601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.780991ms","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-18T09:10:55.820629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.58312ms","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-18T09:10:55.820679Z","caller":"traceutil/trace.go:171","msg":"trace[1809908591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"167.887853ms","start":"2026-04-18T09:10:55.652768Z","end":"2026-04-18T09:10:55.820656Z","steps":["trace[1809908591] 'range keys from in-memory index tree' (duration: 167.561786ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:55.820704Z","caller":"traceutil/trace.go:171","msg":"trace[1523875699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"168.734143ms","start":"2026-04-18T09:10:55.651951Z","end":"2026-04-18T09:10:55.820685Z","steps":["trace[1523875699] 'range keys from in-memory index tree' (duration: 168.380275ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:10:59.249384Z","caller":"traceutil/trace.go:171","msg":"trace[1295990409] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"114.343172ms","start":"2026-04-18T09:10:59.13501Z","end":"2026-04-18T09:10:59.249353Z","steps":["trace[1295990409] 'process raft request' (duration: 97.819788ms)","trace[1295990409] 'compare' (duration: 16.224087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:11:12.725776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.680996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:11:12.725944Z","caller":"traceutil/trace.go:171","msg":"trace[1385880996] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:792; }","duration":"543.496505ms","start":"2026-04-18T09:11:12.182431Z","end":"2026-04-18T09:11:12.725928Z","steps":["trace[1385880996] 'read index received' (duration: 141.549472ms)","trace[1385880996] 'applied index is now lower than readState.Index' (duration: 401.946013ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:11:12.726026Z","caller":"traceutil/trace.go:171","msg":"trace[31227405] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"748.958919ms","start":"2026-04-18T09:11:11.977056Z","end":"2026-04-18T09:11:12.726015Z","steps":["trace[31227405] 'process raft request' (duration: 346.953615ms)","trace[31227405] 'compare' (duration: 401.331438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:11:12.726079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:11.977036Z","time spent":"749.01127ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:11:12.72635Z","caller":"traceutil/trace.go:171","msg":"trace[1082615579] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"736.146712ms","start":"2026-04-18T09:11:11.990192Z","end":"2026-04-18T09:11:12.726338Z","steps":["trace[1082615579] 'process raft request' (duration: 735.679971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:12.726404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:11.990176Z","time spent":"736.198352ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:11:12.726506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.648215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T09:11:12.726502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.055378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wbrdq\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-04-18T09:11:12.726561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.389224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:11:12.726582Z","caller":"traceutil/trace.go:171","msg":"trace[1101257234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"490.445735ms","start":"2026-04-18T09:11:12.236132Z","end":"2026-04-18T09:11:12.726578Z","steps":["trace[1101257234] 'agreement among raft nodes before linearized reading' (duration: 490.414914ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:12.726571Z","caller":"traceutil/trace.go:171","msg":"trace[596546084] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wbrdq; range_end:; response_count:1; response_revision:765; }","duration":"544.161671ms","start":"2026-04-18T09:11:12.182391Z","end":"2026-04-18T09:11:12.726552Z","steps":["trace[596546084] 'agreement among raft nodes before linearized reading' (duration: 543.968756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:12.726613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:12.182374Z","time spent":"544.231142ms","remote":"127.0.0.1:36154","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-wbrdq\" "} {"level":"warn","ts":"2026-04-18T09:11:12.726598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:12.236114Z","time spent":"490.480746ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:11:12.726533Z","caller":"traceutil/trace.go:171","msg":"trace[885022754] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:765; }","duration":"517.720447ms","start":"2026-04-18T09:11:12.208805Z","end":"2026-04-18T09:11:12.726525Z","steps":["trace[885022754] 'agreement among raft nodes before linearized reading' (duration: 517.643875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:12.726785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:12.208783Z","time spent":"517.988663ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:11:12.727193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.957643ms","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-18T09:11:12.727333Z","caller":"traceutil/trace.go:171","msg":"trace[2127800692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"277.045105ms","start":"2026-04-18T09:11:12.450206Z","end":"2026-04-18T09:11:12.727251Z","steps":["trace[2127800692] 'agreement among raft nodes before linearized reading' (duration: 276.652396ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:38.383879Z","caller":"traceutil/trace.go:171","msg":"trace[801130982] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"106.33769ms","start":"2026-04-18T09:11:38.277516Z","end":"2026-04-18T09:11:38.383854Z","steps":["trace[801130982] 'process raft request' (duration: 106.197786ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:47.530081Z","caller":"traceutil/trace.go:171","msg":"trace[720922511] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"138.108807ms","start":"2026-04-18T09:11:47.391912Z","end":"2026-04-18T09:11:47.530021Z","steps":["trace[720922511] 'process raft request' (duration: 137.889251ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:48.826848Z","caller":"traceutil/trace.go:171","msg":"trace[165575215] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"529.072307ms","start":"2026-04-18T09:11:48.297754Z","end":"2026-04-18T09:11:48.826826Z","steps":["trace[165575215] 'process raft request' (duration: 528.931914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:48.826991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:48.297744Z","time spent":"529.172699ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:11:49.608805Z","caller":"traceutil/trace.go:171","msg":"trace[1892738371] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"800.531738ms","start":"2026-04-18T09:11:48.808254Z","end":"2026-04-18T09:11:49.608786Z","steps":["trace[1892738371] 'process raft request' (duration: 800.377564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.609009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145909937s","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-18T09:11:49.608931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:48.808225Z","time spent":"800.638971ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:11:49.609079Z","caller":"traceutil/trace.go:171","msg":"trace[776610919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"1.145993269s","start":"2026-04-18T09:11:48.46306Z","end":"2026-04-18T09:11:49.609053Z","steps":["trace[776610919] 'agreement among raft nodes before linearized reading' (duration: 1.145843536s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.609108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:48.463045Z","time spent":"1.14605528s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T09:11:49.609508Z","caller":"traceutil/trace.go:171","msg":"trace[203938902] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"1.145668961s","start":"2026-04-18T09:11:48.463092Z","end":"2026-04-18T09:11:49.608761Z","steps":["trace[203938902] 'read index received' (duration: 363.586547ms)","trace[203938902] 'applied index is now lower than readState.Index' (duration: 782.081634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:11:49.61195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.901944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:11:49.611965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.579565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:11:49.61201Z","caller":"traceutil/trace.go:171","msg":"trace[694208047] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:981; }","duration":"418.004296ms","start":"2026-04-18T09:11:49.193992Z","end":"2026-04-18T09:11:49.611996Z","steps":["trace[694208047] 'agreement among raft nodes before linearized reading' (duration: 417.910544ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:49.612017Z","caller":"traceutil/trace.go:171","msg":"trace[1211982861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"374.671518ms","start":"2026-04-18T09:11:49.237333Z","end":"2026-04-18T09:11:49.612005Z","steps":["trace[1211982861] 'agreement among raft nodes before linearized reading' (duration: 374.596066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.61204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:49.193974Z","time spent":"418.059447ms","remote":"127.0.0.1:36056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:11:49.612049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.355113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T09:11:49.612065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:49.237311Z","time spent":"374.74589ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:11:49.612099Z","caller":"traceutil/trace.go:171","msg":"trace[489852834] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:981; }","duration":"181.446376ms","start":"2026-04-18T09:11:49.430641Z","end":"2026-04-18T09:11:49.612087Z","steps":["trace[489852834] 'agreement among raft nodes before linearized reading' (duration: 181.360454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.612184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.144391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T09:11:49.612236Z","caller":"traceutil/trace.go:171","msg":"trace[1436485508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"581.245824ms","start":"2026-04-18T09:11:49.030979Z","end":"2026-04-18T09:11:49.612225Z","steps":["trace[1436485508] 'agreement among raft nodes before linearized reading' (duration: 581.106091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.612259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:49.030959Z","time spent":"581.295574ms","remote":"127.0.0.1:36226","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-18T09:11:49.612311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.940353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T09:11:49.612315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.587442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T09:11:49.612342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.738079ms","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-18T09:11:49.612356Z","caller":"traceutil/trace.go:171","msg":"trace[864638375] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:981; }","duration":"332.399834ms","start":"2026-04-18T09:11:49.279943Z","end":"2026-04-18T09:11:49.612343Z","steps":["trace[864638375] 'agreement among raft nodes before linearized reading' (duration: 331.947863ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:49.612355Z","caller":"traceutil/trace.go:171","msg":"trace[2141100990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"638.627473ms","start":"2026-04-18T09:11:48.973718Z","end":"2026-04-18T09:11:49.612345Z","steps":["trace[2141100990] 'agreement among raft nodes before linearized reading' (duration: 638.51877ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:49.612364Z","caller":"traceutil/trace.go:171","msg":"trace[1300173616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"679.817011ms","start":"2026-04-18T09:11:48.93254Z","end":"2026-04-18T09:11:49.612357Z","steps":["trace[1300173616] 'agreement among raft nodes before linearized reading' (duration: 679.295199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:49.612384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:48.932522Z","time spent":"679.858821ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T09:11:49.612385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:49.279916Z","time spent":"332.460015ms","remote":"127.0.0.1:36408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:11:49.612386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:11:48.973683Z","time spent":"638.696064ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T09:12:12.444416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.044541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:12.444887Z","caller":"traceutil/trace.go:171","msg":"trace[1201191980] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"663.386114ms","start":"2026-04-18T09:12:11.781417Z","end":"2026-04-18T09:12:12.444804Z","steps":["trace[1201191980] 'process raft request' (duration: 217.826631ms)","trace[1201191980] 'compare' (duration: 444.863396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:12.445067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:11.781405Z","time spent":"663.566458ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:12.451922Z","caller":"traceutil/trace.go:171","msg":"trace[1466866245] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1097; }","duration":"218.707602ms","start":"2026-04-18T09:12:12.233192Z","end":"2026-04-18T09:12:12.4519Z","steps":["trace[1466866245] 'read index received' (duration: 218.446215ms)","trace[1466866245] 'applied index is now lower than readState.Index' (duration: 260.356µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:12.451971Z","caller":"traceutil/trace.go:171","msg":"trace[1427331394] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"669.754823ms","start":"2026-04-18T09:12:11.782172Z","end":"2026-04-18T09:12:12.451926Z","steps":["trace[1427331394] 'process raft request' (duration: 669.650121ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:12.451988Z","caller":"traceutil/trace.go:171","msg":"trace[2070798794] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"669.108618ms","start":"2026-04-18T09:12:11.782853Z","end":"2026-04-18T09:12:12.451962Z","steps":["trace[2070798794] 'process raft request' (duration: 669.007146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:12.452038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.831344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:12:12.452117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:11.782848Z","time spent":"669.20052ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:12.452157Z","caller":"traceutil/trace.go:171","msg":"trace[2057386329] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"670.614314ms","start":"2026-04-18T09:12:11.781506Z","end":"2026-04-18T09:12:12.45212Z","steps":["trace[2057386329] 'process raft request' (duration: 670.219464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:12.452245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:11.781485Z","time spent":"670.729266ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:12.452122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:11.782164Z","time spent":"669.877066ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:12.452302Z","caller":"traceutil/trace.go:171","msg":"trace[1841037607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"218.918326ms","start":"2026-04-18T09:12:12.233145Z","end":"2026-04-18T09:12:12.452063Z","steps":["trace[1841037607] 'agreement among raft nodes before linearized reading' (duration: 218.848045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:13.611588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.881543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:12:13.611681Z","caller":"traceutil/trace.go:171","msg":"trace[919801068] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1059; }","duration":"244.047037ms","start":"2026-04-18T09:12:13.367615Z","end":"2026-04-18T09:12:13.611662Z","steps":["trace[919801068] 'count revisions from in-memory index tree' (duration: 243.707909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:13.611921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.068631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:13.612001Z","caller":"traceutil/trace.go:171","msg":"trace[143688747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"376.213394ms","start":"2026-04-18T09:12:13.235769Z","end":"2026-04-18T09:12:13.611982Z","steps":["trace[143688747] 'range keys from in-memory index tree' (duration: 375.948927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:13.612057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:13.235739Z","time spent":"376.294406ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:12:14.588586Z","caller":"traceutil/trace.go:171","msg":"trace[936054909] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"132.764231ms","start":"2026-04-18T09:12:14.455797Z","end":"2026-04-18T09:12:14.588562Z","steps":["trace[936054909] 'read index received' (duration: 132.596527ms)","trace[936054909] 'applied index is now lower than readState.Index' (duration: 166.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:14.58867Z","caller":"traceutil/trace.go:171","msg":"trace[1562103226] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"135.374642ms","start":"2026-04-18T09:12:14.453247Z","end":"2026-04-18T09:12:14.588622Z","steps":["trace[1562103226] 'process raft request' (duration: 135.150487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:14.589058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.73842ms","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-18T09:12:14.589144Z","caller":"traceutil/trace.go:171","msg":"trace[1881148091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"132.870073ms","start":"2026-04-18T09:12:14.456253Z","end":"2026-04-18T09:12:14.589123Z","steps":["trace[1881148091] 'agreement among raft nodes before linearized reading' (duration: 132.585466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:14.589116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.251732ms","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-18T09:12:14.589218Z","caller":"traceutil/trace.go:171","msg":"trace[1009903516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"133.401156ms","start":"2026-04-18T09:12:14.455799Z","end":"2026-04-18T09:12:14.5892Z","steps":["trace[1009903516] 'agreement among raft nodes before linearized reading' (duration: 132.999396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:14.589679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.868197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:12:14.589744Z","caller":"traceutil/trace.go:171","msg":"trace[1814564180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"133.934218ms","start":"2026-04-18T09:12:14.455794Z","end":"2026-04-18T09:12:14.589728Z","steps":["trace[1814564180] 'agreement among raft nodes before linearized reading' (duration: 132.985586ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:27.114806Z","caller":"traceutil/trace.go:171","msg":"trace[1426841157] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"444.836797ms","start":"2026-04-18T09:12:26.669939Z","end":"2026-04-18T09:12:27.114776Z","steps":["trace[1426841157] 'read index received' (duration: 444.617831ms)","trace[1426841157] 'applied index is now lower than readState.Index' (duration: 217.926µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:27.114909Z","caller":"traceutil/trace.go:171","msg":"trace[791987436] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"473.469141ms","start":"2026-04-18T09:12:26.641409Z","end":"2026-04-18T09:12:27.114878Z","steps":["trace[791987436] 'process raft request' (duration: 473.236355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.115065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:26.641402Z","time spent":"473.580643ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:27.115111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.455823ms","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-18T09:12:27.115168Z","caller":"traceutil/trace.go:171","msg":"trace[1082975573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"383.552444ms","start":"2026-04-18T09:12:26.731602Z","end":"2026-04-18T09:12:27.115155Z","steps":["trace[1082975573] 'agreement among raft nodes before linearized reading' (duration: 383.36717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.11523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:26.731588Z","time spent":"383.634367ms","remote":"127.0.0.1:36226","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-18T09:12:27.115179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.501163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:12:27.115279Z","caller":"traceutil/trace.go:171","msg":"trace[1635748533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"380.657517ms","start":"2026-04-18T09:12:26.734604Z","end":"2026-04-18T09:12:27.115262Z","steps":["trace[1635748533] 'agreement among raft nodes before linearized reading' (duration: 380.398361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.115324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:26.734585Z","time spent":"380.729188ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T09:12:27.11537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.109203ms","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-18T09:12:27.115415Z","caller":"traceutil/trace.go:171","msg":"trace[992850155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1152; }","duration":"445.475832ms","start":"2026-04-18T09:12:26.669927Z","end":"2026-04-18T09:12:27.115403Z","steps":["trace[992850155] 'agreement among raft nodes before linearized reading' (duration: 444.983801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.115457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:26.66992Z","time spent":"445.527483ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T09:12:27.539708Z","caller":"traceutil/trace.go:171","msg":"trace[1951862124] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"421.101439ms","start":"2026-04-18T09:12:27.118582Z","end":"2026-04-18T09:12:27.539683Z","steps":["trace[1951862124] 'process raft request' (duration: 418.20189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.539839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:27.11856Z","time spent":"421.210601ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:27.546042Z","caller":"traceutil/trace.go:171","msg":"trace[1779383578] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1200; }","duration":"350.985539ms","start":"2026-04-18T09:12:27.195042Z","end":"2026-04-18T09:12:27.546027Z","steps":["trace[1779383578] 'read index received' (duration: 341.753092ms)","trace[1779383578] 'applied index is now lower than readState.Index' (duration: 9.231287ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:27.546074Z","caller":"traceutil/trace.go:171","msg":"trace[86862817] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"427.039978ms","start":"2026-04-18T09:12:27.119006Z","end":"2026-04-18T09:12:27.546046Z","steps":["trace[86862817] 'process raft request' (duration: 426.764502ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:27.546154Z","caller":"traceutil/trace.go:171","msg":"trace[478494697] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"427.000338ms","start":"2026-04-18T09:12:27.11914Z","end":"2026-04-18T09:12:27.546141Z","steps":["trace[478494697] 'process raft request' (duration: 426.828884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.546187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:27.118993Z","time spent":"427.13453ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:27.546228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.814243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:27.546292Z","caller":"traceutil/trace.go:171","msg":"trace[1429281738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"310.913696ms","start":"2026-04-18T09:12:27.235365Z","end":"2026-04-18T09:12:27.546278Z","steps":["trace[1429281738] 'agreement among raft nodes before linearized reading' (duration: 310.819074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.546319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:27.23535Z","time spent":"310.963427ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:12:27.546241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:27.119133Z","time spent":"427.048419ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:27.546413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.366178ms","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-18T09:12:27.546455Z","caller":"traceutil/trace.go:171","msg":"trace[1958292664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"351.43597ms","start":"2026-04-18T09:12:27.195007Z","end":"2026-04-18T09:12:27.546443Z","steps":["trace[1958292664] 'agreement among raft nodes before linearized reading' (duration: 351.285736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:27.54649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:27.19499Z","time spent":"351.493391ms","remote":"127.0.0.1:36226","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-18T09:12:43.821548Z","caller":"traceutil/trace.go:171","msg":"trace[764084753] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"213.300319ms","start":"2026-04-18T09:12:43.608225Z","end":"2026-04-18T09:12:43.821526Z","steps":["trace[764084753] 'process raft request' (duration: 213.151716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:44.237373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.778205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:44.237569Z","caller":"traceutil/trace.go:171","msg":"trace[1478576956] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1277; }","duration":"611.452687ms","start":"2026-04-18T09:12:43.626098Z","end":"2026-04-18T09:12:44.237551Z","steps":["trace[1478576956] 'read index received' (duration: 195.258044ms)","trace[1478576956] 'applied index is now lower than readState.Index' (duration: 416.193363ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:44.237581Z","caller":"traceutil/trace.go:171","msg":"trace[1368696812] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"628.224122ms","start":"2026-04-18T09:12:43.609319Z","end":"2026-04-18T09:12:44.237543Z","steps":["trace[1368696812] 'process raft request' (duration: 370.17135ms)","trace[1368696812] 'compare' (duration: 257.453618ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:44.237625Z","caller":"traceutil/trace.go:171","msg":"trace[143105072] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"626.495861ms","start":"2026-04-18T09:12:43.611106Z","end":"2026-04-18T09:12:44.237601Z","steps":["trace[143105072] 'process raft request' (duration: 626.354898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:44.237743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:43.611096Z","time spent":"626.577413ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:44.23775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:43.60931Z","time spent":"628.349965ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:44.237778Z","caller":"traceutil/trace.go:171","msg":"trace[425637645] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"360.649346ms","start":"2026-04-18T09:12:43.877088Z","end":"2026-04-18T09:12:44.237737Z","steps":["trace[425637645] 'process raft request' (duration: 360.43037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:44.237901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:43.877066Z","time spent":"360.767909ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:44.238043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.935928ms","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-18T09:12:44.238087Z","caller":"traceutil/trace.go:171","msg":"trace[1708371844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"612.015131ms","start":"2026-04-18T09:12:43.626059Z","end":"2026-04-18T09:12:44.238074Z","steps":["trace[1708371844] 'agreement among raft nodes before linearized reading' (duration: 611.54583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:44.238125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:43.626042Z","time spent":"612.074423ms","remote":"127.0.0.1:36226","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-18T09:12:44.944989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.582186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:44.945108Z","caller":"traceutil/trace.go:171","msg":"trace[380805652] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"185.526005ms","start":"2026-04-18T09:12:44.759571Z","end":"2026-04-18T09:12:44.945097Z","steps":["trace[380805652] 'process raft request' (duration: 185.492794ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:44.9452Z","caller":"traceutil/trace.go:171","msg":"trace[1707686398] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"210.683997ms","start":"2026-04-18T09:12:44.734465Z","end":"2026-04-18T09:12:44.945149Z","steps":["trace[1707686398] 'process raft request' (duration: 27.891016ms)","trace[1707686398] 'compare' (duration: 182.474764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:45.65558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.361803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:45.655933Z","caller":"traceutil/trace.go:171","msg":"trace[527634719] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"703.374631ms","start":"2026-04-18T09:12:44.952543Z","end":"2026-04-18T09:12:45.655917Z","steps":["trace[527634719] 'process raft request' (duration: 205.613758ms)","trace[527634719] 'compare' (duration: 497.113317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:45.6561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:44.952441Z","time spent":"703.577275ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:12:45.656123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.386501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-18T09:12:45.656204Z","caller":"traceutil/trace.go:171","msg":"trace[1478930993] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1236; }","duration":"703.500563ms","start":"2026-04-18T09:12:44.95269Z","end":"2026-04-18T09:12:45.65619Z","steps":["trace[1478930993] 'agreement among raft nodes before linearized reading' (duration: 703.307728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:45.656232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:44.95268Z","time spent":"703.546905ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-18T09:12:45.655886Z","caller":"traceutil/trace.go:171","msg":"trace[1062538464] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"703.147795ms","start":"2026-04-18T09:12:44.952722Z","end":"2026-04-18T09:12:45.65587Z","steps":["trace[1062538464] 'read index received' (duration: 16.702463ms)","trace[1062538464] 'applied index is now lower than readState.Index' (duration: 686.430552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:45.656801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.921443ms","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-18T09:12:45.656851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.21513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:12:45.656948Z","caller":"traceutil/trace.go:171","msg":"trace[545522561] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1236; }","duration":"296.352234ms","start":"2026-04-18T09:12:45.360589Z","end":"2026-04-18T09:12:45.656941Z","steps":["trace[545522561] 'agreement among raft nodes before linearized reading' (duration: 296.19655ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:45.656851Z","caller":"traceutil/trace.go:171","msg":"trace[2076665711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"466.005325ms","start":"2026-04-18T09:12:45.190834Z","end":"2026-04-18T09:12:45.656839Z","steps":["trace[2076665711] 'agreement among raft nodes before linearized reading' (duration: 465.880122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:45.656891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.398883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:45.657283Z","caller":"traceutil/trace.go:171","msg":"trace[1953662856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"423.797982ms","start":"2026-04-18T09:12:45.233474Z","end":"2026-04-18T09:12:45.657272Z","steps":["trace[1953662856] 'agreement among raft nodes before linearized reading' (duration: 423.361102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:45.6572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:45.190817Z","time spent":"466.368413ms","remote":"127.0.0.1:36226","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-18T09:12:45.657327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:45.233427Z","time spent":"423.888774ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:12:46.271533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.104426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:46.271762Z","caller":"traceutil/trace.go:171","msg":"trace[526098315] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"446.102227ms","start":"2026-04-18T09:12:45.825642Z","end":"2026-04-18T09:12:46.271744Z","steps":["trace[526098315] 'read index received' (duration: 223.11622ms)","trace[526098315] 'applied index is now lower than readState.Index' (duration: 222.985147ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:46.271838Z","caller":"traceutil/trace.go:171","msg":"trace[242851718] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"611.383196ms","start":"2026-04-18T09:12:45.660443Z","end":"2026-04-18T09:12:46.271827Z","steps":["trace[242851718] 'process raft request' (duration: 388.305027ms)","trace[242851718] 'compare' (duration: 221.964673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:46.2719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:45.660414Z","time spent":"611.447757ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:46.271969Z","caller":"traceutil/trace.go:171","msg":"trace[1672775930] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"237.14327ms","start":"2026-04-18T09:12:46.034801Z","end":"2026-04-18T09:12:46.271944Z","steps":["trace[1672775930] 'process raft request' (duration: 236.903694ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:46.271993Z","caller":"traceutil/trace.go:171","msg":"trace[1178195792] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"611.043478ms","start":"2026-04-18T09:12:45.660864Z","end":"2026-04-18T09:12:46.271907Z","steps":["trace[1178195792] 'process raft request' (duration: 610.763081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:46.272103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.447515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:12:46.272178Z","caller":"traceutil/trace.go:171","msg":"trace[234630339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"446.526997ms","start":"2026-04-18T09:12:45.825637Z","end":"2026-04-18T09:12:46.272164Z","steps":["trace[234630339] 'agreement among raft nodes before linearized reading' (duration: 446.334062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:46.272262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:45.8256Z","time spent":"446.65146ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T09:12:46.272422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:45.660851Z","time spent":"611.279973ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:48.592077Z","caller":"traceutil/trace.go:171","msg":"trace[1417693063] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"311.053139ms","start":"2026-04-18T09:12:48.280989Z","end":"2026-04-18T09:12:48.592042Z","steps":["trace[1417693063] 'process raft request' (duration: 310.816573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:48.592283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.280963Z","time spent":"311.210343ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:48.982555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.38439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:48.982855Z","caller":"traceutil/trace.go:171","msg":"trace[526961505] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"700.002811ms","start":"2026-04-18T09:12:48.282824Z","end":"2026-04-18T09:12:48.982827Z","steps":["trace[526961505] 'process raft request' (duration: 342.273184ms)","trace[526961505] 'compare' (duration: 357.272566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:48.982994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.282796Z","time spent":"700.127774ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:49.199158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.455503ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T09:12:49.199263Z","caller":"traceutil/trace.go:171","msg":"trace[1938412072] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"897.18389ms","start":"2026-04-18T09:12:48.30206Z","end":"2026-04-18T09:12:49.199244Z","steps":["trace[1938412072] 'read index received' (duration: 289.691006ms)","trace[1938412072] 'applied index is now lower than readState.Index' (duration: 607.491334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:49.199466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.629205ms","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-18T09:12:49.199501Z","caller":"traceutil/trace.go:171","msg":"trace[1580769187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1246; }","duration":"884.688207ms","start":"2026-04-18T09:12:48.314802Z","end":"2026-04-18T09:12:49.19949Z","steps":["trace[1580769187] 'agreement among raft nodes before linearized reading' (duration: 884.545294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.199483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.400425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T09:12:49.199536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.314781Z","time spent":"884.746358ms","remote":"127.0.0.1:36226","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-18T09:12:49.199556Z","caller":"traceutil/trace.go:171","msg":"trace[99802002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"897.532479ms","start":"2026-04-18T09:12:48.302007Z","end":"2026-04-18T09:12:49.199539Z","steps":["trace[99802002] 'agreement among raft nodes before linearized reading' (duration: 897.314204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.199597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.301985Z","time spent":"897.6037ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T09:12:49.199809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.749564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T09:12:49.199853Z","caller":"traceutil/trace.go:171","msg":"trace[901017993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"883.813716ms","start":"2026-04-18T09:12:48.31603Z","end":"2026-04-18T09:12:49.199844Z","steps":["trace[901017993] 'agreement among raft nodes before linearized reading' (duration: 883.666193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.199857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.131774ms","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-18T09:12:49.199878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.316023Z","time spent":"883.847627ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T09:12:49.199886Z","caller":"traceutil/trace.go:171","msg":"trace[2024482284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"884.198035ms","start":"2026-04-18T09:12:48.315679Z","end":"2026-04-18T09:12:49.199877Z","steps":["trace[2024482284] 'agreement among raft nodes before linearized reading' (duration: 884.111793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.199919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:48.315659Z","time spent":"884.254176ms","remote":"127.0.0.1:36226","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-18T09:12:49.200018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.801703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:12:49.200043Z","caller":"traceutil/trace.go:171","msg":"trace[918029410] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1246; }","duration":"245.828614ms","start":"2026-04-18T09:12:48.954207Z","end":"2026-04-18T09:12:49.200036Z","steps":["trace[918029410] 'agreement among raft nodes before linearized reading' (duration: 245.788553ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:49.515254Z","caller":"traceutil/trace.go:171","msg":"trace[836519771] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"311.588952ms","start":"2026-04-18T09:12:49.203638Z","end":"2026-04-18T09:12:49.515227Z","steps":["trace[836519771] 'process raft request' (duration: 311.425438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.515419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:49.203613Z","time spent":"311.713025ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:49.563827Z","caller":"traceutil/trace.go:171","msg":"trace[827340150] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1299; }","duration":"328.011958ms","start":"2026-04-18T09:12:49.235795Z","end":"2026-04-18T09:12:49.563807Z","steps":["trace[827340150] 'read index received' (duration: 279.23147ms)","trace[827340150] 'applied index is now lower than readState.Index' (duration: 48.779438ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:49.564218Z","caller":"traceutil/trace.go:171","msg":"trace[977526459] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"360.498712ms","start":"2026-04-18T09:12:49.203705Z","end":"2026-04-18T09:12:49.564203Z","steps":["trace[977526459] 'process raft request' (duration: 359.901338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.564302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:49.20368Z","time spent":"360.560844ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:49.564461Z","caller":"traceutil/trace.go:171","msg":"trace[1493541327] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"360.285058ms","start":"2026-04-18T09:12:49.204168Z","end":"2026-04-18T09:12:49.564453Z","steps":["trace[1493541327] 'process raft request' (duration: 359.572551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.564531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:49.204157Z","time spent":"360.351579ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:49.564893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.105344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:49.564918Z","caller":"traceutil/trace.go:171","msg":"trace[1081234423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"329.200146ms","start":"2026-04-18T09:12:49.23571Z","end":"2026-04-18T09:12:49.56491Z","steps":["trace[1081234423] 'agreement among raft nodes before linearized reading' (duration: 329.160425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.564937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:49.235693Z","time spent":"329.239477ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:12:49.565043Z","caller":"traceutil/trace.go:171","msg":"trace[372326775] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"360.157575ms","start":"2026-04-18T09:12:49.204878Z","end":"2026-04-18T09:12:49.565035Z","steps":["trace[372326775] 'process raft request' (duration: 358.899165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:49.565081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:49.20487Z","time spent":"360.189925ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:50.099364Z","caller":"traceutil/trace.go:171","msg":"trace[551912678] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"160.695241ms","start":"2026-04-18T09:12:49.938645Z","end":"2026-04-18T09:12:50.09934Z","steps":["trace[551912678] 'read index received' (duration: 160.481206ms)","trace[551912678] 'applied index is now lower than readState.Index' (duration: 211.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:50.099453Z","caller":"traceutil/trace.go:171","msg":"trace[1182966617] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"216.436713ms","start":"2026-04-18T09:12:49.882995Z","end":"2026-04-18T09:12:50.099432Z","steps":["trace[1182966617] 'process raft request' (duration: 216.159796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:50.099528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.046741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:50.099562Z","caller":"traceutil/trace.go:171","msg":"trace[1199173365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1251; }","duration":"119.137784ms","start":"2026-04-18T09:12:49.980413Z","end":"2026-04-18T09:12:50.09955Z","steps":["trace[1199173365] 'agreement among raft nodes before linearized reading' (duration: 119.061262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:50.099556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.886116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:50.099646Z","caller":"traceutil/trace.go:171","msg":"trace[75768984] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1251; }","duration":"160.988728ms","start":"2026-04-18T09:12:49.938631Z","end":"2026-04-18T09:12:50.099619Z","steps":["trace[75768984] 'agreement among raft nodes before linearized reading' (duration: 160.841905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:56.87072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811340440481553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:12:57.371286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811340440481553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T09:12:57.574498Z","caller":"traceutil/trace.go:171","msg":"trace[1218651972] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.285730364s","start":"2026-04-18T09:12:56.288744Z","end":"2026-04-18T09:12:57.574475Z","steps":["trace[1218651972] 'process raft request' (duration: 1.28558323s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.574641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.288723Z","time spent":"1.285842347s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:57.862308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.051006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:57.862434Z","caller":"traceutil/trace.go:171","msg":"trace[1603534659] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.204784779s","start":"2026-04-18T09:12:56.657633Z","end":"2026-04-18T09:12:57.862418Z","steps":["trace[1603534659] 'process raft request' (duration: 1.027547618s)","trace[1603534659] 'compare' (duration: 176.693198ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:57.862477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.657604Z","time spent":"1.204856271s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:12:57.862714Z","caller":"traceutil/trace.go:171","msg":"trace[2133375398] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1359; }","duration":"1.492641922s","start":"2026-04-18T09:12:56.370047Z","end":"2026-04-18T09:12:57.862689Z","steps":["trace[2133375398] 'read index received' (duration: 1.204313598s)","trace[2133375398] 'applied index is now lower than readState.Index' (duration: 288.325054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:57.863017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344876546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-18T09:12:57.863082Z","caller":"traceutil/trace.go:171","msg":"trace[1447022828] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1308; }","duration":"1.344980088s","start":"2026-04-18T09:12:56.518086Z","end":"2026-04-18T09:12:57.863066Z","steps":["trace[1447022828] 'agreement among raft nodes before linearized reading' (duration: 1.344791154s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49297966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-18T09:12:57.863136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.518065Z","time spent":"1.345058719s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-18T09:12:57.863117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.079578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:57.863158Z","caller":"traceutil/trace.go:171","msg":"trace[1351863902] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"1.493135574s","start":"2026-04-18T09:12:56.369999Z","end":"2026-04-18T09:12:57.863134Z","steps":["trace[1351863902] 'agreement among raft nodes before linearized reading' (duration: 1.492819626s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:57.8632Z","caller":"traceutil/trace.go:171","msg":"trace[188027542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"625.207321ms","start":"2026-04-18T09:12:57.237972Z","end":"2026-04-18T09:12:57.863179Z","steps":["trace[188027542] 'agreement among raft nodes before linearized reading' (duration: 625.066577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.369978Z","time spent":"1.493234367s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-18T09:12:57.86326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:57.237953Z","time spent":"625.298093ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:12:57.863279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.147278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-04-18T09:12:57.863283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.738574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T09:12:57.863347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.380734ms","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-18T09:12:57.863357Z","caller":"traceutil/trace.go:171","msg":"trace[560067512] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"123.2743ms","start":"2026-04-18T09:12:57.740058Z","end":"2026-04-18T09:12:57.863333Z","steps":["trace[560067512] 'agreement among raft nodes before linearized reading' (duration: 123.139237ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:57.863401Z","caller":"traceutil/trace.go:171","msg":"trace[18774262] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1308; }","duration":"976.478387ms","start":"2026-04-18T09:12:56.886909Z","end":"2026-04-18T09:12:57.863388Z","steps":["trace[18774262] 'agreement among raft nodes before linearized reading' (duration: 976.395105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.886877Z","time spent":"976.559829ms","remote":"127.0.0.1:59540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:12:57.863014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.503562ms","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-18T09:12:57.86354Z","caller":"traceutil/trace.go:171","msg":"trace[470293807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"307.102886ms","start":"2026-04-18T09:12:57.556423Z","end":"2026-04-18T09:12:57.863526Z","steps":["trace[470293807] 'agreement among raft nodes before linearized reading' (duration: 306.456991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:57.556402Z","time spent":"307.173508ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T09:12:57.863395Z","caller":"traceutil/trace.go:171","msg":"trace[1974684984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"206.948819ms","start":"2026-04-18T09:12:57.656429Z","end":"2026-04-18T09:12:57.863378Z","steps":["trace[1974684984] 'agreement among raft nodes before linearized reading' (duration: 206.625472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.086217ms","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-18T09:12:57.86374Z","caller":"traceutil/trace.go:171","msg":"trace[1344483554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"197.779954ms","start":"2026-04-18T09:12:57.665945Z","end":"2026-04-18T09:12:57.863725Z","steps":["trace[1344483554] 'agreement among raft nodes before linearized reading' (duration: 196.999005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219144837s","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-18T09:12:57.86382Z","caller":"traceutil/trace.go:171","msg":"trace[113228732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"1.219232489s","start":"2026-04-18T09:12:56.644574Z","end":"2026-04-18T09:12:57.863806Z","steps":["trace[113228732] 'agreement among raft nodes before linearized reading' (duration: 1.219110346s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.863494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.880502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T09:12:57.863872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:56.644552Z","time spent":"1.219308691s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:12:57.863954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.577514ms","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-18T09:12:57.863972Z","caller":"traceutil/trace.go:171","msg":"trace[1548567595] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1308; }","duration":"449.332853ms","start":"2026-04-18T09:12:57.414566Z","end":"2026-04-18T09:12:57.863899Z","steps":["trace[1548567595] 'agreement among raft nodes before linearized reading' (duration: 448.891152ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:57.864011Z","caller":"traceutil/trace.go:171","msg":"trace[1917625949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"238.665916ms","start":"2026-04-18T09:12:57.625328Z","end":"2026-04-18T09:12:57.863994Z","steps":["trace[1917625949] 'agreement among raft nodes before linearized reading' (duration: 238.501082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.864035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:57.414546Z","time spent":"449.468256ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-18T09:12:57.864237Z","caller":"traceutil/trace.go:171","msg":"trace[796705696] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"852.856037ms","start":"2026-04-18T09:12:57.009877Z","end":"2026-04-18T09:12:57.862733Z","steps":["trace[796705696] 'process raft request' (duration: 852.507069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:57.86441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:57.009855Z","time spent":"854.500177ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:13:03.044931Z","caller":"traceutil/trace.go:171","msg":"trace[200976504] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"160.082766ms","start":"2026-04-18T09:13:02.884824Z","end":"2026-04-18T09:13:03.044906Z","steps":["trace[200976504] 'process raft request' (duration: 159.924773ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:03.048013Z","caller":"traceutil/trace.go:171","msg":"trace[502910809] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1388; }","duration":"145.947214ms","start":"2026-04-18T09:13:02.902052Z","end":"2026-04-18T09:13:03.048Z","steps":["trace[502910809] 'read index received' (duration: 142.717758ms)","trace[502910809] 'applied index is now lower than readState.Index' (duration: 3.228636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:13:03.04821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.143769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T09:13:03.048444Z","caller":"traceutil/trace.go:171","msg":"trace[1382920159] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:1335; }","duration":"146.426405ms","start":"2026-04-18T09:13:02.902003Z","end":"2026-04-18T09:13:03.048429Z","steps":["trace[1382920159] 'agreement among raft nodes before linearized reading' (duration: 146.043386ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:03.048506Z","caller":"traceutil/trace.go:171","msg":"trace[1893612554] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"162.860202ms","start":"2026-04-18T09:13:02.88537Z","end":"2026-04-18T09:13:03.04823Z","steps":["trace[1893612554] 'process raft request' (duration: 162.577585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:08.071379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.397709ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T09:13:08.07161Z","caller":"traceutil/trace.go:171","msg":"trace[1591806142] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"142.455642ms","start":"2026-04-18T09:13:07.929136Z","end":"2026-04-18T09:13:08.071592Z","steps":["trace[1591806142] 'read index received' (duration: 41.321µs)","trace[1591806142] 'applied index is now lower than readState.Index' (duration: 142.413471ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:08.071702Z","caller":"traceutil/trace.go:171","msg":"trace[1227976518] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"155.037208ms","start":"2026-04-18T09:13:07.916626Z","end":"2026-04-18T09:13:08.071663Z","steps":["trace[1227976518] 'process raft request' (duration: 154.852254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:08.071771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.471229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-18T09:13:08.07177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.628066ms","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-18T09:13:08.071806Z","caller":"traceutil/trace.go:171","msg":"trace[1493095347] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1354; }","duration":"141.53216ms","start":"2026-04-18T09:13:07.93026Z","end":"2026-04-18T09:13:08.071792Z","steps":["trace[1493095347] 'agreement among raft nodes before linearized reading' (duration: 141.434598ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:08.071816Z","caller":"traceutil/trace.go:171","msg":"trace[17567697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"142.718248ms","start":"2026-04-18T09:13:07.929085Z","end":"2026-04-18T09:13:08.071803Z","steps":["trace[17567697] 'agreement among raft nodes before linearized reading' (duration: 142.558214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:08.071968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.450505ms","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-18T09:13:08.072012Z","caller":"traceutil/trace.go:171","msg":"trace[701981417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1354; }","duration":"140.493025ms","start":"2026-04-18T09:13:07.931492Z","end":"2026-04-18T09:13:08.071985Z","steps":["trace[701981417] 'agreement among raft nodes before linearized reading' (duration: 140.421984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:08.07209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.593042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:13:08.07212Z","caller":"traceutil/trace.go:171","msg":"trace[989113188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1354; }","duration":"141.648843ms","start":"2026-04-18T09:13:07.930462Z","end":"2026-04-18T09:13:08.072111Z","steps":["trace[989113188] 'agreement among raft nodes before linearized reading' (duration: 141.556971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:08.072206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.045997ms","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-18T09:13:08.072285Z","caller":"traceutil/trace.go:171","msg":"trace[1713637911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1354; }","duration":"132.144279ms","start":"2026-04-18T09:13:07.940121Z","end":"2026-04-18T09:13:08.072265Z","steps":["trace[1713637911] 'agreement among raft nodes before linearized reading' (duration: 131.916864ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:20.707241Z","caller":"traceutil/trace.go:171","msg":"trace[1829898685] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"113.308096ms","start":"2026-04-18T09:13:20.593907Z","end":"2026-04-18T09:13:20.707215Z","steps":["trace[1829898685] 'process raft request' (duration: 113.126212ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:22.35032Z","caller":"traceutil/trace.go:171","msg":"trace[1210783839] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"143.554058ms","start":"2026-04-18T09:13:22.206753Z","end":"2026-04-18T09:13:22.350307Z","steps":["trace[1210783839] 'process raft request' (duration: 143.459766ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:22.350306Z","caller":"traceutil/trace.go:171","msg":"trace[176440273] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"143.533147ms","start":"2026-04-18T09:13:22.206739Z","end":"2026-04-18T09:13:22.350273Z","steps":["trace[176440273] 'process raft request' (duration: 100.904544ms)","trace[176440273] 'compare' (duration: 42.424278ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:22.350521Z","caller":"traceutil/trace.go:171","msg":"trace[725272360] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"143.717292ms","start":"2026-04-18T09:13:22.206793Z","end":"2026-04-18T09:13:22.35051Z","steps":["trace[725272360] 'process raft request' (duration: 143.472326ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:22.35067Z","caller":"traceutil/trace.go:171","msg":"trace[1893491787] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1479; }","duration":"141.890049ms","start":"2026-04-18T09:13:22.208755Z","end":"2026-04-18T09:13:22.350645Z","steps":["trace[1893491787] 'read index received' (duration: 98.99951ms)","trace[1893491787] 'applied index is now lower than readState.Index' (duration: 42.889109ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:22.350734Z","caller":"traceutil/trace.go:171","msg":"trace[1977169129] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"143.393114ms","start":"2026-04-18T09:13:22.207296Z","end":"2026-04-18T09:13:22.35069Z","steps":["trace[1977169129] 'process raft request' (duration: 143.167559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:22.350918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.137785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-18T09:13:22.350934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.373294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:13:22.350956Z","caller":"traceutil/trace.go:171","msg":"trace[1117899717] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1424; }","duration":"142.216196ms","start":"2026-04-18T09:13:22.208728Z","end":"2026-04-18T09:13:22.350945Z","steps":["trace[1117899717] 'agreement among raft nodes before linearized reading' (duration: 142.056422ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:22.350983Z","caller":"traceutil/trace.go:171","msg":"trace[84124982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1424; }","duration":"115.457757ms","start":"2026-04-18T09:13:22.235513Z","end":"2026-04-18T09:13:22.35097Z","steps":["trace[84124982] 'agreement among raft nodes before linearized reading' (duration: 115.343204ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:38.379549Z","caller":"traceutil/trace.go:171","msg":"trace[1586869120] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"102.063922ms","start":"2026-04-18T09:13:38.277434Z","end":"2026-04-18T09:13:38.379498Z","steps":["trace[1586869120] 'process raft request' (duration: 56.636043ms)","trace[1586869120] 'compare' (duration: 45.112632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:13:52.849308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.437674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-18T09:13:52.849531Z","caller":"traceutil/trace.go:171","msg":"trace[1050909726] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1563; }","duration":"117.722581ms","start":"2026-04-18T09:13:52.731781Z","end":"2026-04-18T09:13:52.849503Z","steps":["trace[1050909726] 'range keys from in-memory index tree' (duration: 117.086246ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:52.981456Z","caller":"traceutil/trace.go:171","msg":"trace[1168323650] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"127.531413ms","start":"2026-04-18T09:13:52.85388Z","end":"2026-04-18T09:13:52.981412Z","steps":["trace[1168323650] 'process raft request' (duration: 127.336878ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:14:58.547746Z","caller":"traceutil/trace.go:171","msg":"trace[1182452089] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"113.954183ms","start":"2026-04-18T09:14:58.433763Z","end":"2026-04-18T09:14:58.547718Z","steps":["trace[1182452089] 'process raft request' (duration: 42.05818ms)","trace[1182452089] 'compare' (duration: 71.751149ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:15:01.279627Z","caller":"traceutil/trace.go:171","msg":"trace[1959572925] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"222.237202ms","start":"2026-04-18T09:15:01.057373Z","end":"2026-04-18T09:15:01.27961Z","steps":["trace[1959572925] 'read index received' (duration: 222.075658ms)","trace[1959572925] 'applied index is now lower than readState.Index' (duration: 160.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:15:01.279722Z","caller":"traceutil/trace.go:171","msg":"trace[2006969095] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"233.404145ms","start":"2026-04-18T09:15:01.046283Z","end":"2026-04-18T09:15:01.279687Z","steps":["trace[2006969095] 'process raft request' (duration: 233.22163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:15:01.279853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.612037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-18T09:15:01.279881Z","caller":"traceutil/trace.go:171","msg":"trace[1154196052] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1874; }","duration":"176.689989ms","start":"2026-04-18T09:15:01.103185Z","end":"2026-04-18T09:15:01.279875Z","steps":["trace[1154196052] 'agreement among raft nodes before linearized reading' (duration: 176.619867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:15:01.280075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.277557ms","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-18T09:15:01.280156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.410896ms","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-18T09:15:01.280174Z","caller":"traceutil/trace.go:171","msg":"trace[1821925178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"222.830765ms","start":"2026-04-18T09:15:01.057338Z","end":"2026-04-18T09:15:01.280169Z","steps":["trace[1821925178] 'agreement among raft nodes before linearized reading' (duration: 222.370434ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:01.280174Z","caller":"traceutil/trace.go:171","msg":"trace[1574609020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"178.411789ms","start":"2026-04-18T09:15:01.101738Z","end":"2026-04-18T09:15:01.28015Z","steps":["trace[1574609020] 'agreement among raft nodes before linearized reading' (duration: 178.191574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:15:01.57517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.544287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:15:01.575419Z","caller":"traceutil/trace.go:171","msg":"trace[1924831756] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"291.242185ms","start":"2026-04-18T09:15:01.284159Z","end":"2026-04-18T09:15:01.575401Z","steps":["trace[1924831756] 'process raft request' (duration: 291.201824ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:01.575475Z","caller":"traceutil/trace.go:171","msg":"trace[1703459244] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"292.353742ms","start":"2026-04-18T09:15:01.283088Z","end":"2026-04-18T09:15:01.575442Z","steps":["trace[1703459244] 'process raft request' (duration: 75.413826ms)","trace[1703459244] 'compare' (duration: 216.320402ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:15:01.575505Z","caller":"traceutil/trace.go:171","msg":"trace[1765171588] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"291.498032ms","start":"2026-04-18T09:15:01.283968Z","end":"2026-04-18T09:15:01.575466Z","steps":["trace[1765171588] 'process raft request' (duration: 291.302337ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:05.71863Z","caller":"traceutil/trace.go:171","msg":"trace[924964305] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"122.547104ms","start":"2026-04-18T09:15:05.596055Z","end":"2026-04-18T09:15:05.718602Z","steps":["trace[924964305] 'process raft request' (duration: 122.492553ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:05.718687Z","caller":"traceutil/trace.go:171","msg":"trace[1850780057] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"123.327203ms","start":"2026-04-18T09:15:05.595321Z","end":"2026-04-18T09:15:05.718648Z","steps":["trace[1850780057] 'process raft request' (duration: 123.000576ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:11.957072Z","caller":"traceutil/trace.go:171","msg":"trace[754568752] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"175.223945ms","start":"2026-04-18T09:15:11.781825Z","end":"2026-04-18T09:15:11.957049Z","steps":["trace[754568752] 'process raft request' (duration: 172.792087ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:18.708667Z","caller":"traceutil/trace.go:171","msg":"trace[1734189459] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"121.252975ms","start":"2026-04-18T09:15:18.587389Z","end":"2026-04-18T09:15:18.708642Z","steps":["trace[1734189459] 'process raft request' (duration: 121.108491ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:34.219804Z","caller":"traceutil/trace.go:171","msg":"trace[935804953] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"111.628148ms","start":"2026-04-18T09:15:34.108147Z","end":"2026-04-18T09:15:34.219775Z","steps":["trace[935804953] 'process raft request' (duration: 111.475324ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:15:55.135611Z","caller":"traceutil/trace.go:171","msg":"trace[321433401] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"151.931744ms","start":"2026-04-18T09:15:54.983651Z","end":"2026-04-18T09:15:55.135583Z","steps":["trace[321433401] 'process raft request' (duration: 151.75595ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:16:04.754141Z","caller":"traceutil/trace.go:171","msg":"trace[1038571057] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"221.876493ms","start":"2026-04-18T09:16:04.53224Z","end":"2026-04-18T09:16:04.754116Z","steps":["trace[1038571057] 'read index received' (duration: 221.563186ms)","trace[1038571057] 'applied index is now lower than readState.Index' (duration: 312.307µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:16:04.754313Z","caller":"traceutil/trace.go:171","msg":"trace[1910386803] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"384.098759ms","start":"2026-04-18T09:16:04.370149Z","end":"2026-04-18T09:16:04.754248Z","steps":["trace[1910386803] 'process raft request' (duration: 383.770181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:16:04.754473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:16:04.370125Z","time spent":"384.283922ms","remote":"127.0.0.1:59506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1146,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:16:04.754871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.60022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-18T09:16:04.754945Z","caller":"traceutil/trace.go:171","msg":"trace[1830042801] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2188; }","duration":"222.726794ms","start":"2026-04-18T09:16:04.532201Z","end":"2026-04-18T09:16:04.754928Z","steps":["trace[1830042801] 'agreement among raft nodes before linearized reading' (duration: 222.052008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:16:12.101207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.516177ms","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-18T09:16:12.101302Z","caller":"traceutil/trace.go:171","msg":"trace[934459211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"285.657569ms","start":"2026-04-18T09:16:11.81562Z","end":"2026-04-18T09:16:12.101278Z","steps":["trace[934459211] 'range keys from in-memory index tree' (duration: 285.212029ms)"],"step_count":1}