{"level":"warn","ts":"2026-07-01T00:50:45.988821Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:50:45.988961Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-07-01T00:50:45.989072Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:50:45.989091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T00:50:45.989118Z","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-07-01T00:50:45.98997Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-01T00:50:45.990057Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-07-01T00:50:45.995765Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.488124ms"} {"level":"info","ts":"2026-07-01T00:50:46.001721Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-01T00:50:46.00184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:50:46.001879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-01T00:50:46.0019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:50:46.001915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-01T00:50:46.001981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-01T00:50:46.007611Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:50:46.009039Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:50:46.010099Z","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-07-01T00:50:46.012788Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:50:46.013063Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:50:46.013381Z","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-07-01T00:50:46.014272Z","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-07-01T00:50:46.014548Z","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-07-01T00:50:46.014652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-01T00:50:46.015005Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T00:50:46.017654Z","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-07-01T00:50:46.017804Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T00:50:46.017854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T00:50:46.017963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:50:46.017996Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:50:46.802527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:50:46.802582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:50:46.802606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-01T00:50:46.802617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:50:46.802622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T00:50:46.802633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-01T00:50:46.80264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T00:50:46.803329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:50:46.803393Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:46.803562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:50:46.803618Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:50:46.803705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:50:46.803743Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:50:46.80774Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:46.807852Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:46.807889Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:46.810814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:50:46.812324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-01T00:51:10.255824Z","caller":"traceutil/trace.go:171","msg":"trace[1089283915] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"184.5157ms","start":"2026-07-01T00:51:10.07126Z","end":"2026-07-01T00:51:10.255776Z","steps":["trace[1089283915] 'process raft request' (duration: 158.525441ms)","trace[1089283915] 'compare' (duration: 25.791234ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:30.459582Z","caller":"traceutil/trace.go:171","msg":"trace[728886190] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"234.827779ms","start":"2026-07-01T00:51:30.224709Z","end":"2026-07-01T00:51:30.459537Z","steps":["trace[728886190] 'process raft request' (duration: 228.814823ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:30.671465Z","caller":"traceutil/trace.go:171","msg":"trace[623207902] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"375.384204ms","start":"2026-07-01T00:51:30.29577Z","end":"2026-07-01T00:51:30.671155Z","steps":["trace[623207902] 'read index received' (duration: 157.471658ms)","trace[623207902] 'applied index is now lower than readState.Index' (duration: 217.911476ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:30.671616Z","caller":"traceutil/trace.go:171","msg":"trace[1508115902] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"417.16489ms","start":"2026-07-01T00:51:30.254093Z","end":"2026-07-01T00:51:30.671258Z","steps":["trace[1508115902] 'process raft request' (duration: 404.418881ms)","trace[1508115902] 'compare' (duration: 11.873209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:30.671831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.824004ms","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-07-01T00:51:30.672Z","caller":"traceutil/trace.go:171","msg":"trace[1794906747] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"376.227643ms","start":"2026-07-01T00:51:30.295741Z","end":"2026-07-01T00:51:30.671969Z","steps":["trace[1794906747] 'agreement among raft nodes before linearized reading' (duration: 375.62979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:30.672332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.419017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T00:51:30.672351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.946058ms","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-07-01T00:51:30.672389Z","caller":"traceutil/trace.go:171","msg":"trace[138778320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:659; }","duration":"331.506869ms","start":"2026-07-01T00:51:30.340867Z","end":"2026-07-01T00:51:30.672374Z","steps":["trace[138778320] 'agreement among raft nodes before linearized reading' (duration: 331.348506ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:30.672416Z","caller":"traceutil/trace.go:171","msg":"trace[328261850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"275.00569ms","start":"2026-07-01T00:51:30.397389Z","end":"2026-07-01T00:51:30.672395Z","steps":["trace[328261850] 'agreement among raft nodes before linearized reading' (duration: 274.839106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:30.672419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.835938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:51:30.672529Z","caller":"traceutil/trace.go:171","msg":"trace[1762258871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"310.000942ms","start":"2026-07-01T00:51:30.362501Z","end":"2026-07-01T00:51:30.672502Z","steps":["trace[1762258871] 'agreement among raft nodes before linearized reading' (duration: 309.763677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:30.672571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:30.36248Z","time spent":"310.086715ms","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:51:30.67265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:30.295727Z","time spent":"376.355556ms","remote":"127.0.0.1:48888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-07-01T00:51:30.672839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.865167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-01T00:51:30.672971Z","caller":"traceutil/trace.go:171","msg":"trace[1073154737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"181.036681ms","start":"2026-07-01T00:51:30.491908Z","end":"2026-07-01T00:51:30.672945Z","steps":["trace[1073154737] 'agreement among raft nodes before linearized reading' (duration: 180.397697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:30.673081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:30.254063Z","time spent":"417.58267ms","remote":"127.0.0.1:48888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:51:30.672428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:30.340851Z","time spent":"331.56709ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T00:51:31.4444Z","caller":"traceutil/trace.go:171","msg":"trace[408783513] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"113.109552ms","start":"2026-07-01T00:51:31.331238Z","end":"2026-07-01T00:51:31.444348Z","steps":["trace[408783513] 'process raft request' (duration: 112.841846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:35.19454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.100273ms","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-07-01T00:51:35.194671Z","caller":"traceutil/trace.go:171","msg":"trace[1405669924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"200.296577ms","start":"2026-07-01T00:51:34.99434Z","end":"2026-07-01T00:51:35.194636Z","steps":["trace[1405669924] 'range keys from in-memory index tree' (duration: 199.803976ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:37.314011Z","caller":"traceutil/trace.go:171","msg":"trace[675904289] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"104.535278ms","start":"2026-07-01T00:51:37.209445Z","end":"2026-07-01T00:51:37.313981Z","steps":["trace[675904289] 'process raft request' (duration: 104.033686ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:39.76419Z","caller":"traceutil/trace.go:171","msg":"trace[120970291] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"260.651653ms","start":"2026-07-01T00:51:39.503499Z","end":"2026-07-01T00:51:39.764151Z","steps":["trace[120970291] 'process raft request' (duration: 260.444899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:39.926885Z","caller":"traceutil/trace.go:171","msg":"trace[363887450] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"109.187363ms","start":"2026-07-01T00:51:39.817655Z","end":"2026-07-01T00:51:39.926843Z","steps":["trace[363887450] 'process raft request' (duration: 93.914787ms)","trace[363887450] 'compare' (duration: 14.99822ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:42.981682Z","caller":"traceutil/trace.go:171","msg":"trace[1553751320] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"135.461489ms","start":"2026-07-01T00:51:42.846175Z","end":"2026-07-01T00:51:42.981636Z","steps":["trace[1553751320] 'process raft request' (duration: 104.372375ms)","trace[1553751320] 'compare' (duration: 30.804108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:53.900911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.238853ms","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-07-01T00:51:53.901069Z","caller":"traceutil/trace.go:171","msg":"trace[757451290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"419.459688ms","start":"2026-07-01T00:51:53.481583Z","end":"2026-07-01T00:51:53.901043Z","steps":["trace[757451290] 'range keys from in-memory index tree' (duration: 419.013178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:53.901134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:53.481569Z","time spent":"419.54578ms","remote":"127.0.0.1:49120","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-07-01T00:51:53.901151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.441168ms","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-07-01T00:51:53.901262Z","caller":"traceutil/trace.go:171","msg":"trace[959151157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"419.594662ms","start":"2026-07-01T00:51:53.481621Z","end":"2026-07-01T00:51:53.901238Z","steps":["trace[959151157] 'range keys from in-memory index tree' (duration: 419.237823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:53.901495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:53.48161Z","time spent":"419.871118ms","remote":"127.0.0.1:49120","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-07-01T00:51:53.901605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.945305ms","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-07-01T00:51:53.901704Z","caller":"traceutil/trace.go:171","msg":"trace[1487962873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"436.13151ms","start":"2026-07-01T00:51:53.465549Z","end":"2026-07-01T00:51:53.90168Z","steps":["trace[1487962873] 'range keys from in-memory index tree' (duration: 435.507065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:53.90174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:53.465516Z","time spent":"436.216001ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T00:51:54.008529Z","caller":"traceutil/trace.go:171","msg":"trace[44835113] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"102.571744ms","start":"2026-07-01T00:51:53.90593Z","end":"2026-07-01T00:51:54.008502Z","steps":["trace[44835113] 'process raft request' (duration: 102.41899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:54.022197Z","caller":"traceutil/trace.go:171","msg":"trace[1251648806] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"115.53385ms","start":"2026-07-01T00:51:53.906619Z","end":"2026-07-01T00:51:54.022153Z","steps":["trace[1251648806] 'process raft request' (duration: 115.331325ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:54.022644Z","caller":"traceutil/trace.go:171","msg":"trace[344622054] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"115.96843ms","start":"2026-07-01T00:51:53.906592Z","end":"2026-07-01T00:51:54.022561Z","steps":["trace[344622054] 'process raft request' (duration: 115.51724ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:03.026506Z","caller":"traceutil/trace.go:171","msg":"trace[731466808] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"125.007307ms","start":"2026-07-01T00:52:02.901472Z","end":"2026-07-01T00:52:03.026479Z","steps":["trace[731466808] 'process raft request' (duration: 124.809713ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:03.444465Z","caller":"traceutil/trace.go:171","msg":"trace[281737392] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"110.613898ms","start":"2026-07-01T00:52:03.333817Z","end":"2026-07-01T00:52:03.444431Z","steps":["trace[281737392] 'process raft request' (duration: 91.712616ms)","trace[281737392] 'compare' (duration: 18.766609ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:29.004861Z","caller":"traceutil/trace.go:171","msg":"trace[1106759475] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"207.684188ms","start":"2026-07-01T00:52:28.797157Z","end":"2026-07-01T00:52:29.004841Z","steps":["trace[1106759475] 'read index received' (duration: 207.075024ms)","trace[1106759475] 'applied index is now lower than readState.Index' (duration: 608.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:29.004902Z","caller":"traceutil/trace.go:171","msg":"trace[457828971] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"214.588846ms","start":"2026-07-01T00:52:28.790295Z","end":"2026-07-01T00:52:29.004884Z","steps":["trace[457828971] 'process raft request' (duration: 214.425052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:29.004988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.820691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:29.00501Z","caller":"traceutil/trace.go:171","msg":"trace[466877745] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1036; }","duration":"207.903382ms","start":"2026-07-01T00:52:28.7971Z","end":"2026-07-01T00:52:29.005003Z","steps":["trace[466877745] 'agreement among raft nodes before linearized reading' (duration: 207.81672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:29.277268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.672671ms","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-07-01T00:52:29.277336Z","caller":"traceutil/trace.go:171","msg":"trace[1736390118] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1036; }","duration":"229.799323ms","start":"2026-07-01T00:52:29.047522Z","end":"2026-07-01T00:52:29.277321Z","steps":["trace[1736390118] 'count revisions from in-memory index tree' (duration: 229.477145ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:29.580318Z","caller":"traceutil/trace.go:171","msg":"trace[2022428289] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"298.652457ms","start":"2026-07-01T00:52:29.281638Z","end":"2026-07-01T00:52:29.58029Z","steps":["trace[2022428289] 'process raft request' (duration: 297.957901ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:29.837538Z","caller":"traceutil/trace.go:171","msg":"trace[631653308] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1075; }","duration":"291.047472ms","start":"2026-07-01T00:52:29.546455Z","end":"2026-07-01T00:52:29.837503Z","steps":["trace[631653308] 'read index received' (duration: 32.524413ms)","trace[631653308] 'applied index is now lower than readState.Index' (duration: 258.521229ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:29.837659Z","caller":"traceutil/trace.go:171","msg":"trace[1014597962] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"554.368332ms","start":"2026-07-01T00:52:29.283266Z","end":"2026-07-01T00:52:29.837634Z","steps":["trace[1014597962] 'process raft request' (duration: 554.044754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:29.837794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:29.28319Z","time spent":"554.525375ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:29.837824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.366209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:29.837885Z","caller":"traceutil/trace.go:171","msg":"trace[1316101247] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1038; }","duration":"291.472712ms","start":"2026-07-01T00:52:29.5464Z","end":"2026-07-01T00:52:29.837873Z","steps":["trace[1316101247] 'agreement among raft nodes before linearized reading' (duration: 291.282998ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:54.347629Z","caller":"traceutil/trace.go:171","msg":"trace[246774753] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"586.679771ms","start":"2026-07-01T00:52:53.76091Z","end":"2026-07-01T00:52:54.34759Z","steps":["trace[246774753] 'process raft request' (duration: 586.464906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.347868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.760884Z","time spent":"586.866065ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:54.40798Z","caller":"traceutil/trace.go:171","msg":"trace[25355944] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"464.050438ms","start":"2026-07-01T00:52:53.943903Z","end":"2026-07-01T00:52:54.407954Z","steps":["trace[25355944] 'read index received' (duration: 403.368371ms)","trace[25355944] 'applied index is now lower than readState.Index' (duration: 60.681147ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:54.408178Z","caller":"traceutil/trace.go:171","msg":"trace[991858658] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"554.887744ms","start":"2026-07-01T00:52:53.85323Z","end":"2026-07-01T00:52:54.408118Z","steps":["trace[991858658] 'process raft request' (duration: 528.823278ms)","trace[991858658] 'compare' (duration: 25.78728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:54.408305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.361045ms","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-07-01T00:52:54.408367Z","caller":"traceutil/trace.go:171","msg":"trace[479098634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"464.455288ms","start":"2026-07-01T00:52:53.943896Z","end":"2026-07-01T00:52:54.408352Z","steps":["trace[479098634] 'agreement among raft nodes before linearized reading' (duration: 464.198402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.408367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.845202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:54.408429Z","caller":"traceutil/trace.go:171","msg":"trace[690610320] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1118; }","duration":"445.969345ms","start":"2026-07-01T00:52:53.962441Z","end":"2026-07-01T00:52:54.40841Z","steps":["trace[690610320] 'agreement among raft nodes before linearized reading' (duration: 445.842352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.40845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.853195Z","time spent":"555.11589ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:54.408484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.962413Z","time spent":"446.057087ms","remote":"127.0.0.1:49182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:52:54.40843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.943841Z","time spent":"464.57397ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:52:55.671175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.315256ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:52:55.671469Z","caller":"traceutil/trace.go:171","msg":"trace[1886700510] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"437.088411ms","start":"2026-07-01T00:52:55.234363Z","end":"2026-07-01T00:52:55.671452Z","steps":["trace[1886700510] 'process raft request' (duration: 436.902527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:55.671589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:55.234336Z","time spent":"437.169443ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:56.84114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.764256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-01T00:52:56.841192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.355142ms","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-07-01T00:52:56.841222Z","caller":"traceutil/trace.go:171","msg":"trace[2018384010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"190.886989ms","start":"2026-07-01T00:52:56.650313Z","end":"2026-07-01T00:52:56.8412Z","steps":["trace[2018384010] 'range keys from in-memory index tree' (duration: 190.571691ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:56.841264Z","caller":"traceutil/trace.go:171","msg":"trace[1748559576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"153.486455ms","start":"2026-07-01T00:52:56.687759Z","end":"2026-07-01T00:52:56.841246Z","steps":["trace[1748559576] 'range keys from in-memory index tree' (duration: 153.029145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.841293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.709211ms","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-07-01T00:52:56.841374Z","caller":"traceutil/trace.go:171","msg":"trace[1155452946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"153.843014ms","start":"2026-07-01T00:52:56.687513Z","end":"2026-07-01T00:52:56.841356Z","steps":["trace[1155452946] 'range keys from in-memory index tree' (duration: 153.516026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.841988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.671781ms","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-07-01T00:52:56.842253Z","caller":"traceutil/trace.go:171","msg":"trace[1947774788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"338.822565ms","start":"2026-07-01T00:52:56.503249Z","end":"2026-07-01T00:52:56.842072Z","steps":["trace[1947774788] 'range keys from in-memory index tree' (duration: 338.502668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.842316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:56.503227Z","time spent":"339.074551ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T00:53:09.137799Z","caller":"traceutil/trace.go:171","msg":"trace[1712905098] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"119.51984ms","start":"2026-07-01T00:53:09.018257Z","end":"2026-07-01T00:53:09.137777Z","steps":["trace[1712905098] 'read index received' (duration: 94.587914ms)","trace[1712905098] 'applied index is now lower than readState.Index' (duration: 24.931046ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:09.137906Z","caller":"traceutil/trace.go:171","msg":"trace[663016927] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"133.84877ms","start":"2026-07-01T00:53:09.004022Z","end":"2026-07-01T00:53:09.137871Z","steps":["trace[663016927] 'process raft request' (duration: 108.843873ms)","trace[663016927] 'compare' (duration: 24.800783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:09.138028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.739975ms","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-07-01T00:53:09.13809Z","caller":"traceutil/trace.go:171","msg":"trace[303825348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"119.845987ms","start":"2026-07-01T00:53:09.018231Z","end":"2026-07-01T00:53:09.138077Z","steps":["trace[303825348] 'agreement among raft nodes before linearized reading' (duration: 119.643592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:12.024908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.005066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:12.02498Z","caller":"traceutil/trace.go:171","msg":"trace[412333899] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1225; }","duration":"470.119388ms","start":"2026-07-01T00:53:11.554845Z","end":"2026-07-01T00:53:12.024964Z","steps":["trace[412333899] 'count revisions from in-memory index tree' (duration: 469.7505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:12.02505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.554831Z","time spent":"470.20824ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:12.025406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.604024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:12.025508Z","caller":"traceutil/trace.go:171","msg":"trace[1552980465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"662.742757ms","start":"2026-07-01T00:53:11.362745Z","end":"2026-07-01T00:53:12.025488Z","steps":["trace[1552980465] 'range keys from in-memory index tree' (duration: 662.369529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:12.025547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.362729Z","time spent":"662.808968ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:53:12.02791Z","caller":"traceutil/trace.go:171","msg":"trace[125391069] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"303.296425ms","start":"2026-07-01T00:53:11.724604Z","end":"2026-07-01T00:53:12.027901Z","steps":["trace[125391069] 'process raft request' (duration: 303.220003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:12.027981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.724584Z","time spent":"303.355347ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:12.028715Z","caller":"traceutil/trace.go:171","msg":"trace[1186476694] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"240.409413ms","start":"2026-07-01T00:53:11.78829Z","end":"2026-07-01T00:53:12.0287Z","steps":["trace[1186476694] 'read index received' (duration: 239.610665ms)","trace[1186476694] 'applied index is now lower than readState.Index' (duration: 797.658µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:12.028799Z","caller":"traceutil/trace.go:171","msg":"trace[433127902] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"265.988944ms","start":"2026-07-01T00:53:11.7628Z","end":"2026-07-01T00:53:12.028789Z","steps":["trace[433127902] 'process raft request' (duration: 265.814439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:12.028929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.627878ms","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-07-01T00:53:12.028986Z","caller":"traceutil/trace.go:171","msg":"trace[1958775165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1227; }","duration":"240.71491ms","start":"2026-07-01T00:53:11.788256Z","end":"2026-07-01T00:53:12.02897Z","steps":["trace[1958775165] 'agreement among raft nodes before linearized reading' (duration: 240.630498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.666791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411439248768173,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:53:13.68115Z","caller":"traceutil/trace.go:171","msg":"trace[1722245764] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"515.288921ms","start":"2026-07-01T00:53:13.165834Z","end":"2026-07-01T00:53:13.681123Z","steps":["trace[1722245764] 'read index received' (duration: 515.039056ms)","trace[1722245764] 'applied index is now lower than readState.Index' (duration: 248.845µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:13.681233Z","caller":"traceutil/trace.go:171","msg":"trace[1221643615] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"702.234019ms","start":"2026-07-01T00:53:12.978967Z","end":"2026-07-01T00:53:13.681201Z","steps":["trace[1221643615] 'process raft request' (duration: 701.926462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.681377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:12.978938Z","time spent":"702.356031ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:13.681389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.189998ms","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-07-01T00:53:13.681377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.680917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:13.681457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.635539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T00:53:13.681482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.734521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-01T00:53:13.681507Z","caller":"traceutil/trace.go:171","msg":"trace[754492756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"515.722331ms","start":"2026-07-01T00:53:13.165776Z","end":"2026-07-01T00:53:13.681498Z","steps":["trace[754492756] 'agreement among raft nodes before linearized reading' (duration: 515.456845ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:13.6815Z","caller":"traceutil/trace.go:171","msg":"trace[1348335161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"320.83845ms","start":"2026-07-01T00:53:13.360631Z","end":"2026-07-01T00:53:13.681469Z","steps":["trace[1348335161] 'agreement among raft nodes before linearized reading' (duration: 320.693047ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:13.681529Z","caller":"traceutil/trace.go:171","msg":"trace[1998758015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"434.804993ms","start":"2026-07-01T00:53:13.246716Z","end":"2026-07-01T00:53:13.681521Z","steps":["trace[1998758015] 'agreement among raft nodes before linearized reading' (duration: 434.669039ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:13.681429Z","caller":"traceutil/trace.go:171","msg":"trace[1588407467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"437.279389ms","start":"2026-07-01T00:53:13.244137Z","end":"2026-07-01T00:53:13.681416Z","steps":["trace[1588407467] 'agreement among raft nodes before linearized reading' (duration: 437.136196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.681554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.24671Z","time spent":"434.839733ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:53:13.681562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.244109Z","time spent":"437.443953ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T00:53:13.681566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.360612Z","time spent":"320.938812ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:53:13.681896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.165753Z","time spent":"516.134691ms","remote":"127.0.0.1:49120","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-07-01T00:53:26.661785Z","caller":"traceutil/trace.go:171","msg":"trace[1154815990] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"109.268154ms","start":"2026-07-01T00:53:26.552492Z","end":"2026-07-01T00:53:26.66176Z","steps":["trace[1154815990] 'process raft request' (duration: 109.131801ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:28.367112Z","caller":"traceutil/trace.go:171","msg":"trace[1113882632] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"130.970205ms","start":"2026-07-01T00:53:28.236128Z","end":"2026-07-01T00:53:28.367098Z","steps":["trace[1113882632] 'read index received' (duration: 130.822082ms)","trace[1113882632] 'applied index is now lower than readState.Index' (duration: 147.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:28.367421Z","caller":"traceutil/trace.go:171","msg":"trace[1484318691] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"241.468947ms","start":"2026-07-01T00:53:28.125937Z","end":"2026-07-01T00:53:28.367406Z","steps":["trace[1484318691] 'process raft request' (duration: 241.058678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:28.367741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.565738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:28.367794Z","caller":"traceutil/trace.go:171","msg":"trace[1969451255] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1287; }","duration":"131.705822ms","start":"2026-07-01T00:53:28.236076Z","end":"2026-07-01T00:53:28.367782Z","steps":["trace[1969451255] 'agreement among raft nodes before linearized reading' (duration: 131.574779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.240798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.879879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:33.240895Z","caller":"traceutil/trace.go:171","msg":"trace[1066566192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"879.016052ms","start":"2026-07-01T00:53:32.361849Z","end":"2026-07-01T00:53:33.240865Z","steps":["trace[1066566192] 'range keys from in-memory index tree' (duration: 878.806517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.240937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:32.361831Z","time spent":"879.096464ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:53:33.241184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001988142s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:33.241259Z","caller":"traceutil/trace.go:171","msg":"trace[1646928827] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1311; }","duration":"1.002066744s","start":"2026-07-01T00:53:32.239183Z","end":"2026-07-01T00:53:33.241249Z","steps":["trace[1646928827] 'range keys from in-memory index tree' (duration: 1.001978882s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.241472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.130312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:53:33.241553Z","caller":"traceutil/trace.go:171","msg":"trace[1843581365] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"850.382271ms","start":"2026-07-01T00:53:32.391156Z","end":"2026-07-01T00:53:33.241538Z","steps":["trace[1843581365] 'process raft request' (duration: 398.118115ms)","trace[1843581365] 'compare' (duration: 451.717163ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:33.241611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:32.391139Z","time spent":"850.446312ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:33.752892Z","caller":"traceutil/trace.go:171","msg":"trace[1523835865] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"1.240967242s","start":"2026-07-01T00:53:32.511889Z","end":"2026-07-01T00:53:33.752856Z","steps":["trace[1523835865] 'read index received' (duration: 277.329556ms)","trace[1523835865] 'applied index is now lower than readState.Index' (duration: 963.636356ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:33.752998Z","caller":"traceutil/trace.go:171","msg":"trace[326326180] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.349594541s","start":"2026-07-01T00:53:32.403394Z","end":"2026-07-01T00:53:33.752988Z","steps":["trace[326326180] 'process raft request' (duration: 1.349329145s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.753146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:32.403379Z","time spent":"1.349683503s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:33.753216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241300069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:33.753296Z","caller":"traceutil/trace.go:171","msg":"trace[1265803805] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1313; }","duration":"1.241404482s","start":"2026-07-01T00:53:32.511874Z","end":"2026-07-01T00:53:33.753278Z","steps":["trace[1265803805] 'agreement among raft nodes before linearized reading' (duration: 1.241250708s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.753391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:32.511821Z","time spent":"1.241556225s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:33.768006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.791961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:33.76804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.337316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T00:53:33.768062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.226757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T00:53:33.768099Z","caller":"traceutil/trace.go:171","msg":"trace[58555787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"991.440959ms","start":"2026-07-01T00:53:32.776645Z","end":"2026-07-01T00:53:33.768086Z","steps":["trace[58555787] 'agreement among raft nodes before linearized reading' (duration: 991.268995ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:33.768083Z","caller":"traceutil/trace.go:171","msg":"trace[1651439973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"524.880233ms","start":"2026-07-01T00:53:33.243174Z","end":"2026-07-01T00:53:33.768054Z","steps":["trace[1651439973] 'agreement among raft nodes before linearized reading' (duration: 524.804571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.768133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:32.776626Z","time spent":"991.50118ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T00:53:33.768098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.785251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:33.76814Z","caller":"traceutil/trace.go:171","msg":"trace[278522546] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1314; }","duration":"330.328849ms","start":"2026-07-01T00:53:33.437773Z","end":"2026-07-01T00:53:33.768102Z","steps":["trace[278522546] 'agreement among raft nodes before linearized reading' (duration: 330.162505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.768187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:33.437744Z","time spent":"330.433232ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":137,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:53:33.768191Z","caller":"traceutil/trace.go:171","msg":"trace[1798919781] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1314; }","duration":"647.926775ms","start":"2026-07-01T00:53:33.120242Z","end":"2026-07-01T00:53:33.768169Z","steps":["trace[1798919781] 'agreement among raft nodes before linearized reading' (duration: 647.776461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:33.768234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:33.120221Z","time spent":"648.002966ms","remote":"127.0.0.1:53234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:33.768136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:33.243154Z","time spent":"524.976025ms","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:53:33.981412Z","caller":"traceutil/trace.go:171","msg":"trace[434948525] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"105.189749ms","start":"2026-07-01T00:53:33.876195Z","end":"2026-07-01T00:53:33.981385Z","steps":["trace[434948525] 'process raft request' (duration: 97.443491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:35.200428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.942128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:53:35.200544Z","caller":"traceutil/trace.go:171","msg":"trace[1049737630] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"839.165601ms","start":"2026-07-01T00:53:34.361359Z","end":"2026-07-01T00:53:35.200525Z","steps":["trace[1049737630] 'read index received' (duration: 341.937617ms)","trace[1049737630] 'applied index is now lower than readState.Index' (duration: 497.222904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:35.200691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.314425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:35.200673Z","caller":"traceutil/trace.go:171","msg":"trace[1137950800] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"886.488855ms","start":"2026-07-01T00:53:34.314145Z","end":"2026-07-01T00:53:35.200634Z","steps":["trace[1137950800] 'process raft request' (duration: 389.212409ms)","trace[1137950800] 'compare' (duration: 495.743001ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:35.200727Z","caller":"traceutil/trace.go:171","msg":"trace[2007850975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"839.391477ms","start":"2026-07-01T00:53:34.361321Z","end":"2026-07-01T00:53:35.200713Z","steps":["trace[2007850975] 'agreement among raft nodes before linearized reading' (duration: 839.303155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:35.200776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:34.361306Z","time spent":"839.453069ms","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:53:35.200832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:34.314125Z","time spent":"886.624338ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:35.216266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.58949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:35.216333Z","caller":"traceutil/trace.go:171","msg":"trace[1702953908] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1326; }","duration":"231.664771ms","start":"2026-07-01T00:53:34.984653Z","end":"2026-07-01T00:53:35.216317Z","steps":["trace[1702953908] 'agreement among raft nodes before linearized reading' (duration: 231.552639ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:35.216376Z","caller":"traceutil/trace.go:171","msg":"trace[553053650] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"212.321324ms","start":"2026-07-01T00:53:35.003968Z","end":"2026-07-01T00:53:35.216289Z","steps":["trace[553053650] 'process raft request' (duration: 212.126479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:35.718623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.90421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:53:35.71873Z","caller":"traceutil/trace.go:171","msg":"trace[790314489] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"356.596446ms","start":"2026-07-01T00:53:35.362116Z","end":"2026-07-01T00:53:35.718713Z","steps":["trace[790314489] 'read index received' (duration: 255.648034ms)","trace[790314489] 'applied index is now lower than readState.Index' (duration: 100.946962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:35.718956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.818892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:35.719052Z","caller":"traceutil/trace.go:171","msg":"trace[203915470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"356.957974ms","start":"2026-07-01T00:53:35.362067Z","end":"2026-07-01T00:53:35.719025Z","steps":["trace[203915470] 'agreement among raft nodes before linearized reading' (duration: 356.78322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:35.719058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.59262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:35.7191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:35.362046Z","time spent":"357.044507ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:53:35.719134Z","caller":"traceutil/trace.go:171","msg":"trace[153174357] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1326; }","duration":"202.699152ms","start":"2026-07-01T00:53:35.516417Z","end":"2026-07-01T00:53:35.719116Z","steps":["trace[153174357] 'agreement among raft nodes before linearized reading' (duration: 202.548829ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:35.907609Z","caller":"traceutil/trace.go:171","msg":"trace[1049315304] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"103.590783ms","start":"2026-07-01T00:53:35.803976Z","end":"2026-07-01T00:53:35.907567Z","steps":["trace[1049315304] 'process raft request' (duration: 103.375658ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:36.085515Z","caller":"traceutil/trace.go:171","msg":"trace[908552169] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"100.361358ms","start":"2026-07-01T00:53:35.985128Z","end":"2026-07-01T00:53:36.085489Z","steps":["trace[908552169] 'read index received' (duration: 14.137256ms)","trace[908552169] 'applied index is now lower than readState.Index' (duration: 86.223212ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:36.085655Z","caller":"traceutil/trace.go:171","msg":"trace[603350307] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"112.205871ms","start":"2026-07-01T00:53:35.973418Z","end":"2026-07-01T00:53:36.085624Z","steps":["trace[603350307] 'process raft request' (duration: 25.881707ms)","trace[603350307] 'compare' (duration: 86.051148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:36.085772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.622294ms","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-07-01T00:53:36.085846Z","caller":"traceutil/trace.go:171","msg":"trace[1997327358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1330; }","duration":"100.731747ms","start":"2026-07-01T00:53:35.985094Z","end":"2026-07-01T00:53:36.085826Z","steps":["trace[1997327358] 'agreement among raft nodes before linearized reading' (duration: 100.532322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.433521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411439248768470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:53:42.435189Z","caller":"traceutil/trace.go:171","msg":"trace[1716284586] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"501.81691ms","start":"2026-07-01T00:53:41.933343Z","end":"2026-07-01T00:53:42.43516Z","steps":["trace[1716284586] 'read index received' (duration: 501.579595ms)","trace[1716284586] 'applied index is now lower than readState.Index' (duration: 236.135µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:42.435314Z","caller":"traceutil/trace.go:171","msg":"trace[1671148365] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"636.03423ms","start":"2026-07-01T00:53:41.799238Z","end":"2026-07-01T00:53:42.435273Z","steps":["trace[1671148365] 'process raft request' (duration: 635.695102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.71895ms","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-07-01T00:53:42.435501Z","caller":"traceutil/trace.go:171","msg":"trace[1112201498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"440.822982ms","start":"2026-07-01T00:53:41.994662Z","end":"2026-07-01T00:53:42.435485Z","steps":["trace[1112201498] 'agreement among raft nodes before linearized reading' (duration: 440.660758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:41.799199Z","time spent":"636.204984ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:42.435502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.130297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T00:53:42.43554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:41.994625Z","time spent":"440.905994ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T00:53:42.435584Z","caller":"traceutil/trace.go:171","msg":"trace[885790205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"502.269721ms","start":"2026-07-01T00:53:41.933295Z","end":"2026-07-01T00:53:42.435564Z","steps":["trace[885790205] 'agreement among raft nodes before linearized reading' (duration: 501.997865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.720376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T00:53:42.43563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:41.933275Z","time spent":"502.346992ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T00:53:42.43566Z","caller":"traceutil/trace.go:171","msg":"trace[637140382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"239.808379ms","start":"2026-07-01T00:53:42.195832Z","end":"2026-07-01T00:53:42.43564Z","steps":["trace[637140382] 'agreement among raft nodes before linearized reading' (duration: 239.626234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.855712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:42.435723Z","caller":"traceutil/trace.go:171","msg":"trace[118613259] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1361; }","duration":"359.925073ms","start":"2026-07-01T00:53:42.075788Z","end":"2026-07-01T00:53:42.435713Z","steps":["trace[118613259] 'agreement among raft nodes before linearized reading' (duration: 359.864542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.07577Z","time spent":"359.971604ms","remote":"127.0.0.1:48956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:42.435795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.636629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:42.435759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.1441ms","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-07-01T00:53:42.435778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.856829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-01T00:53:42.435827Z","caller":"traceutil/trace.go:171","msg":"trace[225486032] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1361; }","duration":"308.68967ms","start":"2026-07-01T00:53:42.127128Z","end":"2026-07-01T00:53:42.435818Z","steps":["trace[225486032] 'agreement among raft nodes before linearized reading' (duration: 308.636568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.127118Z","time spent":"308.746601ms","remote":"127.0.0.1:49150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:53:42.435873Z","caller":"traceutil/trace.go:171","msg":"trace[1826019089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"280.338355ms","start":"2026-07-01T00:53:42.155506Z","end":"2026-07-01T00:53:42.435845Z","steps":["trace[1826019089] 'agreement among raft nodes before linearized reading' (duration: 279.926966ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:42.435888Z","caller":"traceutil/trace.go:171","msg":"trace[772153539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"327.017453ms","start":"2026-07-01T00:53:42.108844Z","end":"2026-07-01T00:53:42.435862Z","steps":["trace[772153539] 'agreement among raft nodes before linearized reading' (duration: 326.679465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:42.435945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.108821Z","time spent":"327.114425ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:53:43.851012Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023330395s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T00:53:43.851818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024136344s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:53:43.852098Z","caller":"traceutil/trace.go:171","msg":"trace[1769548568] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.410144949s","start":"2026-07-01T00:53:42.441932Z","end":"2026-07-01T00:53:43.852077Z","steps":["trace[1769548568] 'process raft request' (duration: 1.410059117s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:43.852081Z","caller":"traceutil/trace.go:171","msg":"trace[1470520433] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.410812415s","start":"2026-07-01T00:53:42.441201Z","end":"2026-07-01T00:53:43.852014Z","steps":["trace[1470520433] 'process raft request' (duration: 386.384884ms)","trace[1470520433] 'compare' (duration: 1.023854767s)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:43.852173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.441918Z","time spent":"1.410223702s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:43.852261Z","caller":"traceutil/trace.go:171","msg":"trace[1897212904] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.41057633s","start":"2026-07-01T00:53:42.441662Z","end":"2026-07-01T00:53:43.852238Z","steps":["trace[1897212904] 'process raft request' (duration: 1.410361665s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.852347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.441163Z","time spent":"1.41104078s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:43.85237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.441649Z","time spent":"1.410650081s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:43.852487Z","caller":"traceutil/trace.go:171","msg":"trace[2144694700] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1417; }","duration":"1.276930852s","start":"2026-07-01T00:53:42.575547Z","end":"2026-07-01T00:53:43.852477Z","steps":["trace[2144694700] 'read index received' (duration: 252.127163ms)","trace[2144694700] 'applied index is now lower than readState.Index' (duration: 1.024802869s)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:43.852533Z","caller":"traceutil/trace.go:171","msg":"trace[1178049280] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.410008226s","start":"2026-07-01T00:53:42.442519Z","end":"2026-07-01T00:53:43.852527Z","steps":["trace[1178049280] 'process raft request' (duration: 1.409660778s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.852565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.442499Z","time spent":"1.410044377s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:43.852624Z","caller":"traceutil/trace.go:171","msg":"trace[1911972975] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.411088541s","start":"2026-07-01T00:53:42.441523Z","end":"2026-07-01T00:53:43.852612Z","steps":["trace[1911972975] 'process raft request' (duration: 1.410388905s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.852643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277110477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:43.852668Z","caller":"traceutil/trace.go:171","msg":"trace[322651655] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1366; }","duration":"1.277177809s","start":"2026-07-01T00:53:42.575481Z","end":"2026-07-01T00:53:43.852659Z","steps":["trace[322651655] 'agreement among raft nodes before linearized reading' (duration: 1.277130467s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.852688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.575447Z","time spent":"1.277236389s","remote":"127.0.0.1:49270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:43.852687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:42.441493Z","time spent":"1.411160023s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:43.861786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.284005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:43.861872Z","caller":"traceutil/trace.go:171","msg":"trace[237974023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"500.425668ms","start":"2026-07-01T00:53:43.361427Z","end":"2026-07-01T00:53:43.861852Z","steps":["trace[237974023] 'agreement among raft nodes before linearized reading' (duration: 500.282505ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:43.861815Z","caller":"traceutil/trace.go:171","msg":"trace[651619419] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"262.453842ms","start":"2026-07-01T00:53:43.599308Z","end":"2026-07-01T00:53:43.861762Z","steps":["trace[651619419] 'process raft request' (duration: 262.297898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.861912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.361405Z","time spent":"500.49881ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:53:43.862108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.470907ms","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":"info","ts":"2026-07-01T00:53:43.862352Z","caller":"traceutil/trace.go:171","msg":"trace[1263801688] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"422.745864ms","start":"2026-07-01T00:53:43.439593Z","end":"2026-07-01T00:53:43.862338Z","steps":["trace[1263801688] 'agreement among raft nodes before linearized reading' (duration: 422.408926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.863453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.439576Z","time spent":"423.837069ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T00:53:44.406372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.847062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:53:44.406536Z","caller":"traceutil/trace.go:171","msg":"trace[1876171640] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"104.367861ms","start":"2026-07-01T00:53:44.302144Z","end":"2026-07-01T00:53:44.406512Z","steps":["trace[1876171640] 'read index received' (duration: 2.207921ms)","trace[1876171640] 'applied index is now lower than readState.Index' (duration: 102.15831ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:44.40668Z","caller":"traceutil/trace.go:171","msg":"trace[1036257943] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"145.005379ms","start":"2026-07-01T00:53:44.26164Z","end":"2026-07-01T00:53:44.406645Z","steps":["trace[1036257943] 'process raft request' (duration: 42.704106ms)","trace[1036257943] 'compare' (duration: 101.645428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:44.406763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.605496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-01T00:53:44.406794Z","caller":"traceutil/trace.go:171","msg":"trace[2063237070] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1373; }","duration":"104.674018ms","start":"2026-07-01T00:53:44.302108Z","end":"2026-07-01T00:53:44.406782Z","steps":["trace[2063237070] 'agreement among raft nodes before linearized reading' (duration: 104.461463ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:44.407786Z","caller":"traceutil/trace.go:171","msg":"trace[190804679] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"104.182967ms","start":"2026-07-01T00:53:44.303582Z","end":"2026-07-01T00:53:44.407765Z","steps":["trace[190804679] 'process raft request' (duration: 104.036113ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:45.602448Z","caller":"traceutil/trace.go:171","msg":"trace[522416196] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1444; }","duration":"147.20484ms","start":"2026-07-01T00:53:45.455204Z","end":"2026-07-01T00:53:45.602409Z","steps":["trace[522416196] 'read index received' (duration: 15.782884ms)","trace[522416196] 'applied index is now lower than readState.Index' (duration: 131.415756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:45.602715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.459236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:45.602782Z","caller":"traceutil/trace.go:171","msg":"trace[271608847] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1390; }","duration":"147.598689ms","start":"2026-07-01T00:53:45.455162Z","end":"2026-07-01T00:53:45.60276Z","steps":["trace[271608847] 'agreement among raft nodes before linearized reading' (duration: 147.407815ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:45.60349Z","caller":"traceutil/trace.go:171","msg":"trace[1275980384] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"156.708679ms","start":"2026-07-01T00:53:45.446759Z","end":"2026-07-01T00:53:45.603468Z","steps":["trace[1275980384] 'process raft request' (duration: 128.872516ms)","trace[1275980384] 'compare' (duration: 26.363259ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:45.603944Z","caller":"traceutil/trace.go:171","msg":"trace[1556824601] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"153.775432ms","start":"2026-07-01T00:53:45.450151Z","end":"2026-07-01T00:53:45.603926Z","steps":["trace[1556824601] 'process raft request' (duration: 152.125894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:55.407631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.681713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:53:55.40804Z","caller":"traceutil/trace.go:171","msg":"trace[1394708828] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1493; }","duration":"114.782991ms","start":"2026-07-01T00:53:55.293235Z","end":"2026-07-01T00:53:55.408018Z","steps":["trace[1394708828] 'read index received' (duration: 14.514155ms)","trace[1394708828] 'applied index is now lower than readState.Index' (duration: 100.267676ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:55.408101Z","caller":"traceutil/trace.go:171","msg":"trace[131931939] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"164.671263ms","start":"2026-07-01T00:53:55.243386Z","end":"2026-07-01T00:53:55.408058Z","steps":["trace[131931939] 'process raft request' (duration: 164.407317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:55.4083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.068018ms","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:534"} {"level":"info","ts":"2026-07-01T00:53:55.408329Z","caller":"traceutil/trace.go:171","msg":"trace[1803891697] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1436; }","duration":"115.331604ms","start":"2026-07-01T00:53:55.292984Z","end":"2026-07-01T00:53:55.408316Z","steps":["trace[1803891697] 'agreement among raft nodes before linearized reading' (duration: 115.15601ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:38.234159Z","caller":"traceutil/trace.go:171","msg":"trace[2006845086] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"105.418723ms","start":"2026-07-01T00:54:38.128702Z","end":"2026-07-01T00:54:38.234121Z","steps":["trace[2006845086] 'process raft request' (duration: 105.162487ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:48.933624Z","caller":"traceutil/trace.go:171","msg":"trace[715942458] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"162.345997ms","start":"2026-07-01T00:54:48.771251Z","end":"2026-07-01T00:54:48.933597Z","steps":["trace[715942458] 'process raft request' (duration: 162.179284ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:25.89402Z","caller":"traceutil/trace.go:171","msg":"trace[1199711920] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"172.229676ms","start":"2026-07-01T00:55:25.72175Z","end":"2026-07-01T00:55:25.89398Z","steps":["trace[1199711920] 'process raft request' (duration: 171.991941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:55:26.738604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.667988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:55:26.73872Z","caller":"traceutil/trace.go:171","msg":"trace[343366774] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"177.230574ms","start":"2026-07-01T00:55:26.561472Z","end":"2026-07-01T00:55:26.738703Z","steps":["trace[343366774] 'read index received' (duration: 49.383512ms)","trace[343366774] 'applied index is now lower than readState.Index' (duration: 127.845702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:55:26.738936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.509204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-01T00:55:26.738945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.454769ms","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-07-01T00:55:26.738974Z","caller":"traceutil/trace.go:171","msg":"trace[200629514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"136.580025ms","start":"2026-07-01T00:55:26.602382Z","end":"2026-07-01T00:55:26.738963Z","steps":["trace[200629514] 'agreement among raft nodes before linearized reading' (duration: 136.429982ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:26.739024Z","caller":"traceutil/trace.go:171","msg":"trace[944331015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1853; }","duration":"177.579342ms","start":"2026-07-01T00:55:26.561425Z","end":"2026-07-01T00:55:26.739004Z","steps":["trace[944331015] 'agreement among raft nodes before linearized reading' (duration: 177.348817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:55:26.739011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.936944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T00:55:26.739089Z","caller":"traceutil/trace.go:171","msg":"trace[1030764415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"122.069958ms","start":"2026-07-01T00:55:26.617004Z","end":"2026-07-01T00:55:26.739074Z","steps":["trace[1030764415] 'agreement among raft nodes before linearized reading' (duration: 121.847202ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:26.739234Z","caller":"traceutil/trace.go:171","msg":"trace[2068580034] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"219.563671ms","start":"2026-07-01T00:55:26.519165Z","end":"2026-07-01T00:55:26.738728Z","steps":["trace[2068580034] 'process raft request' (duration: 91.688328ms)","trace[2068580034] 'compare' (duration: 127.517074ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:55:26.913585Z","caller":"traceutil/trace.go:171","msg":"trace[1529654258] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"169.926273ms","start":"2026-07-01T00:55:26.743631Z","end":"2026-07-01T00:55:26.913557Z","steps":["trace[1529654258] 'process raft request' (duration: 169.864852ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:26.913639Z","caller":"traceutil/trace.go:171","msg":"trace[1745395609] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"170.163229ms","start":"2026-07-01T00:55:26.743444Z","end":"2026-07-01T00:55:26.913607Z","steps":["trace[1745395609] 'process raft request' (duration: 109.946805ms)","trace[1745395609] 'compare' (duration: 59.990549ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:56:01.400974Z","caller":"traceutil/trace.go:171","msg":"trace[186572772] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"147.591373ms","start":"2026-07-01T00:56:01.253351Z","end":"2026-07-01T00:56:01.400942Z","steps":["trace[186572772] 'process raft request' (duration: 97.152436ms)","trace[186572772] 'compare' (duration: 50.022777ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:57:32.218146Z","caller":"traceutil/trace.go:171","msg":"trace[2083357681] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"172.219344ms","start":"2026-07-01T00:57:32.045902Z","end":"2026-07-01T00:57:32.218121Z","steps":["trace[2083357681] 'process raft request' (duration: 171.712732ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:57:38.955853Z","caller":"traceutil/trace.go:171","msg":"trace[2119011605] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"114.634165ms","start":"2026-07-01T00:57:38.841188Z","end":"2026-07-01T00:57:38.955822Z","steps":["trace[2119011605] 'process raft request' (duration: 114.474532ms)"],"step_count":1}