{"level":"warn","ts":"2026-07-15T03:16:29.69563Z","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-15T03:16:29.695769Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-15T03:16:29.695952Z","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-15T03:16:29.695969Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-15T03:16:29.696025Z","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-15T03:16:29.696684Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-07-15T03:16:29.696826Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-15T03:16:29.699969Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.899635ms"} {"level":"info","ts":"2026-07-15T03:16:29.709035Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-07-15T03:16:29.709109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T03:16:29.709131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-07-15T03:16:29.70922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T03:16:29.709237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-07-15T03:16:29.709336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-07-15T03:16:29.713267Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T03:16:29.714599Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T03:16:29.715957Z","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-15T03:16:29.718281Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T03:16:29.718455Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T03:16:29.718763Z","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-15T03:16:29.718854Z","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-15T03:16:29.718904Z","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-15T03:16:29.719444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-07-15T03:16:29.719592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-15T03:16:29.722709Z","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-15T03:16:29.722881Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-15T03:16:29.722948Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-15T03:16:29.724276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T03:16:29.724318Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T03:16:30.309922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T03:16:30.310072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T03:16:30.310162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-07-15T03:16:30.310221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-07-15T03:16:30.310234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-15T03:16:30.310299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-07-15T03:16:30.310321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-15T03:16:30.311346Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:16:30.3123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T03:16:30.312405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:16:30.312366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:16:30.312554Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:16:30.312556Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T03:16:30.312693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:16:30.312717Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T03:16:30.312729Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:16:30.314155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-07-15T03:16:30.316717Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T03:16:43.632614Z","caller":"traceutil/trace.go:171","msg":"trace[868672913] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"196.720783ms","start":"2026-07-15T03:16:43.435859Z","end":"2026-07-15T03:16:43.63258Z","steps":["trace[868672913] 'process raft request' (duration: 130.638436ms)","trace[868672913] 'compare' (duration: 65.880343ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:17:22.964205Z","caller":"traceutil/trace.go:171","msg":"trace[1188736693] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"125.989576ms","start":"2026-07-15T03:17:22.83818Z","end":"2026-07-15T03:17:22.96417Z","steps":["trace[1188736693] 'process raft request' (duration: 125.351834ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:17:23.46179Z","caller":"traceutil/trace.go:171","msg":"trace[230385597] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"238.79544ms","start":"2026-07-15T03:17:23.222963Z","end":"2026-07-15T03:17:23.461759Z","steps":["trace[230385597] 'read index received' (duration: 226.784629ms)","trace[230385597] 'applied index is now lower than readState.Index' (duration: 12.008981ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:17:23.461832Z","caller":"traceutil/trace.go:171","msg":"trace[96783850] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"431.801972ms","start":"2026-07-15T03:17:23.029984Z","end":"2026-07-15T03:17:23.461786Z","steps":["trace[96783850] 'process raft request' (duration: 419.821252ms)","trace[96783850] 'compare' (duration: 11.631632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:17:23.462101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.687838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:17:23.46219Z","caller":"traceutil/trace.go:171","msg":"trace[265650216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"189.819411ms","start":"2026-07-15T03:17:23.272359Z","end":"2026-07-15T03:17:23.462178Z","steps":["trace[265650216] 'agreement among raft nodes before linearized reading' (duration: 189.693339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:17:23.462551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:17:23.02995Z","time spent":"431.981536ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T03:17:23.462878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.211019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s6msm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T03:17:23.462919Z","caller":"traceutil/trace.go:171","msg":"trace[392452077] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s6msm; range_end:; response_count:1; response_revision:617; }","duration":"152.2756ms","start":"2026-07-15T03:17:23.310633Z","end":"2026-07-15T03:17:23.462908Z","steps":["trace[392452077] 'agreement among raft nodes before linearized reading' (duration: 151.394493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:17:23.463101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.060334ms","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-15T03:17:23.463205Z","caller":"traceutil/trace.go:171","msg":"trace[1471437457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"240.261809ms","start":"2026-07-15T03:17:23.222914Z","end":"2026-07-15T03:17:23.463176Z","steps":["trace[1471437457] 'agreement among raft nodes before linearized reading' (duration: 239.023565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:17:23.463468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.745941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T03:17:23.463543Z","caller":"traceutil/trace.go:171","msg":"trace[1588644526] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:617; }","duration":"223.897664ms","start":"2026-07-15T03:17:23.239626Z","end":"2026-07-15T03:17:23.463523Z","steps":["trace[1588644526] 'agreement among raft nodes before linearized reading' (duration: 222.340664ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:12.226856Z","caller":"traceutil/trace.go:171","msg":"trace[749385374] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"104.449497ms","start":"2026-07-15T03:18:12.122378Z","end":"2026-07-15T03:18:12.226827Z","steps":["trace[749385374] 'process raft request' (duration: 104.297364ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:33.427891Z","caller":"traceutil/trace.go:171","msg":"trace[1272137880] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"184.420954ms","start":"2026-07-15T03:18:33.243446Z","end":"2026-07-15T03:18:33.427867Z","steps":["trace[1272137880] 'read index received' (duration: 184.294432ms)","trace[1272137880] 'applied index is now lower than readState.Index' (duration: 125.092µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:33.42819Z","caller":"traceutil/trace.go:171","msg":"trace[878358558] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"249.120357ms","start":"2026-07-15T03:18:33.179058Z","end":"2026-07-15T03:18:33.428178Z","steps":["trace[878358558] 'process raft request' (duration: 248.656879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:33.428408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.982745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T03:18:33.42843Z","caller":"traceutil/trace.go:171","msg":"trace[1205321965] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1062; }","duration":"185.054426ms","start":"2026-07-15T03:18:33.243368Z","end":"2026-07-15T03:18:33.428422Z","steps":["trace[1205321965] 'agreement among raft nodes before linearized reading' (duration: 184.951764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:33.428638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.021347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:18:33.428763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.067541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:18:33.428802Z","caller":"traceutil/trace.go:171","msg":"trace[680666286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"156.292632ms","start":"2026-07-15T03:18:33.272484Z","end":"2026-07-15T03:18:33.428777Z","steps":["trace[680666286] 'agreement among raft nodes before linearized reading' (duration: 156.030717ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:33.428862Z","caller":"traceutil/trace.go:171","msg":"trace[14191506] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1062; }","duration":"128.229293ms","start":"2026-07-15T03:18:33.300611Z","end":"2026-07-15T03:18:33.428841Z","steps":["trace[14191506] 'agreement among raft nodes before linearized reading' (duration: 127.925488ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:38.054146Z","caller":"traceutil/trace.go:171","msg":"trace[1975221173] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"169.999902ms","start":"2026-07-15T03:18:37.884109Z","end":"2026-07-15T03:18:38.054109Z","steps":["trace[1975221173] 'read index received' (duration: 169.639475ms)","trace[1975221173] 'applied index is now lower than readState.Index' (duration: 359.067µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:38.054382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.238176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:38.054553Z","caller":"traceutil/trace.go:171","msg":"trace[1828325289] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"183.365877ms","start":"2026-07-15T03:18:37.871088Z","end":"2026-07-15T03:18:38.054454Z","steps":["trace[1828325289] 'process raft request' (duration: 182.801977ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:38.055229Z","caller":"traceutil/trace.go:171","msg":"trace[347822674] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1078; }","duration":"170.571842ms","start":"2026-07-15T03:18:37.884042Z","end":"2026-07-15T03:18:38.054613Z","steps":["trace[347822674] 'agreement among raft nodes before linearized reading' (duration: 170.209045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:39.988876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.221839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:18:39.988958Z","caller":"traceutil/trace.go:171","msg":"trace[1038814409] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"528.020117ms","start":"2026-07-15T03:18:39.460923Z","end":"2026-07-15T03:18:39.988943Z","steps":["trace[1038814409] 'read index received' (duration: 54.361µs)","trace[1038814409] 'applied index is now lower than readState.Index' (duration: 527.964286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:39.989089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.17619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T03:18:39.989114Z","caller":"traceutil/trace.go:171","msg":"trace[1917345878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"528.242192ms","start":"2026-07-15T03:18:39.460866Z","end":"2026-07-15T03:18:39.989108Z","steps":["trace[1917345878] 'agreement among raft nodes before linearized reading' (duration: 528.117079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:39.989142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:39.460846Z","time spent":"528.291252ms","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T03:18:39.989169Z","caller":"traceutil/trace.go:171","msg":"trace[2005767577] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"1.129340731s","start":"2026-07-15T03:18:38.859786Z","end":"2026-07-15T03:18:39.989127Z","steps":["trace[2005767577] 'process raft request' (duration: 220.591481ms)","trace[2005767577] 'compare' (duration: 907.506786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:39.98932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:38.859763Z","time spent":"1.129484504s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:40.559472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700599478457968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T03:18:41.935086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708104701s","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-15T03:18:41.935239Z","caller":"traceutil/trace.go:171","msg":"trace[419151573] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1082; }","duration":"1.708262264s","start":"2026-07-15T03:18:40.226951Z","end":"2026-07-15T03:18:41.935214Z","steps":["trace[419151573] 'range keys from in-memory index tree' (duration: 1.708087231s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.935497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018683128s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:18:41.93582Z","caller":"traceutil/trace.go:171","msg":"trace[163054246] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.074237453s","start":"2026-07-15T03:18:40.861564Z","end":"2026-07-15T03:18:41.935801Z","steps":["trace[163054246] 'process raft request' (duration: 1.074153032s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.935923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.86153Z","time spent":"1.074340045s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:18:41.935818Z","caller":"traceutil/trace.go:171","msg":"trace[979194160] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.941422425s","start":"2026-07-15T03:18:39.994305Z","end":"2026-07-15T03:18:41.935728Z","steps":["trace[979194160] 'process raft request' (duration: 922.38599ms)","trace[979194160] 'compare' (duration: 1.018377403s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:41.936271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:39.994285Z","time spent":"1.941779812s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:18:41.936598Z","caller":"traceutil/trace.go:171","msg":"trace[1532149430] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"1.876865868s","start":"2026-07-15T03:18:40.05891Z","end":"2026-07-15T03:18:41.935776Z","steps":["trace[1532149430] 'read index received' (duration: 857.796272ms)","trace[1532149430] 'applied index is now lower than readState.Index' (duration: 1.019067736s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:41.936835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772057908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T03:18:41.937012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878072731s","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-15T03:18:41.937151Z","caller":"traceutil/trace.go:171","msg":"trace[1180099684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"1.878250184s","start":"2026-07-15T03:18:40.058872Z","end":"2026-07-15T03:18:41.937123Z","steps":["trace[1180099684] 'agreement among raft nodes before linearized reading' (duration: 1.877900237s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.937164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.346643ms","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":"warn","ts":"2026-07-15T03:18:41.937211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.058855Z","time spent":"1.878344706s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T03:18:41.93724Z","caller":"traceutil/trace.go:171","msg":"trace[1639114385] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1084; }","duration":"455.448135ms","start":"2026-07-15T03:18:41.481766Z","end":"2026-07-15T03:18:41.937214Z","steps":["trace[1639114385] 'agreement among raft nodes before linearized reading' (duration: 455.363684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.937314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.481746Z","time spent":"455.551647ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:41.93705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12982642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:41.937383Z","caller":"traceutil/trace.go:171","msg":"trace[300764794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1084; }","duration":"1.130198657s","start":"2026-07-15T03:18:40.807168Z","end":"2026-07-15T03:18:41.937367Z","steps":["trace[300764794] 'agreement among raft nodes before linearized reading' (duration: 1.129759839s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.937615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.716665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:18:41.937647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.807074Z","time spent":"1.130536233s","remote":"127.0.0.1:33320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:41.937742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.871011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:18:41.937951Z","caller":"traceutil/trace.go:171","msg":"trace[1876742383] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1084; }","duration":"935.126096ms","start":"2026-07-15T03:18:41.002818Z","end":"2026-07-15T03:18:41.937944Z","steps":["trace[1876742383] 'agreement among raft nodes before linearized reading' (duration: 934.866581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.937983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.002791Z","time spent":"935.183486ms","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-15T03:18:41.937664Z","caller":"traceutil/trace.go:171","msg":"trace[2104357751] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1084; }","duration":"778.820148ms","start":"2026-07-15T03:18:41.158831Z","end":"2026-07-15T03:18:41.937651Z","steps":["trace[2104357751] 'agreement among raft nodes before linearized reading' (duration: 778.727016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.93831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470480553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-15T03:18:41.938366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.158802Z","time spent":"779.542661ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:41.938378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16114023s","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-15T03:18:41.93839Z","caller":"traceutil/trace.go:171","msg":"trace[219581646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"1.470607526s","start":"2026-07-15T03:18:40.467764Z","end":"2026-07-15T03:18:41.938372Z","steps":["trace[219581646] 'agreement among raft nodes before linearized reading' (duration: 1.470242239s)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:41.93842Z","caller":"traceutil/trace.go:171","msg":"trace[1300647785] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1084; }","duration":"1.161245092s","start":"2026-07-15T03:18:40.777161Z","end":"2026-07-15T03:18:41.938406Z","steps":["trace[1300647785] 'agreement among raft nodes before linearized reading' (duration: 1.161186181s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.938437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.467744Z","time spent":"1.470683837s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T03:18:41.938461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.777134Z","time spent":"1.161318763s","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:41.93883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66689413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:41.938873Z","caller":"traceutil/trace.go:171","msg":"trace[511375093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"1.666995192s","start":"2026-07-15T03:18:40.271867Z","end":"2026-07-15T03:18:41.938862Z","steps":["trace[511375093] 'agreement among raft nodes before linearized reading' (duration: 1.666932721s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.938901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.271836Z","time spent":"1.667059493s","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:18:41.939144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341147093s","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-15T03:18:41.93965Z","caller":"traceutil/trace.go:171","msg":"trace[1050359720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"1.341685333s","start":"2026-07-15T03:18:40.59795Z","end":"2026-07-15T03:18:41.939635Z","steps":["trace[1050359720] 'agreement among raft nodes before linearized reading' (duration: 1.33997484s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.939697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.597933Z","time spent":"1.341755544s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T03:18:41.936881Z","caller":"traceutil/trace.go:171","msg":"trace[2018320609] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1084; }","duration":"1.772112399s","start":"2026-07-15T03:18:40.164758Z","end":"2026-07-15T03:18:41.93687Z","steps":["trace[2018320609] 'agreement among raft nodes before linearized reading' (duration: 1.772028057s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:41.940035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:40.164602Z","time spent":"1.775421722s","remote":"127.0.0.1:44408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:42.447472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700599478457973,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T03:18:42.71185Z","caller":"traceutil/trace.go:171","msg":"trace[1434351532] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"770.137501ms","start":"2026-07-15T03:18:41.941692Z","end":"2026-07-15T03:18:42.71183Z","steps":["trace[1434351532] 'process raft request' (duration: 770.021979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:42.712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.941657Z","time spent":"770.268375ms","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:43.406412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.306934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:18:43.406609Z","caller":"traceutil/trace.go:171","msg":"trace[1544715767] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1126; }","duration":"1.459523254s","start":"2026-07-15T03:18:41.947065Z","end":"2026-07-15T03:18:43.406588Z","steps":["trace[1544715767] 'read index received' (duration: 764.641446ms)","trace[1544715767] 'applied index is now lower than readState.Index' (duration: 694.880618ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:43.406795Z","caller":"traceutil/trace.go:171","msg":"trace[2002951848] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.464268064s","start":"2026-07-15T03:18:41.942516Z","end":"2026-07-15T03:18:43.406784Z","steps":["trace[2002951848] 'process raft request' (duration: 877.504351ms)","trace[2002951848] 'compare' (duration: 585.949777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:43.406846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.942493Z","time spent":"1.464328605s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:43.407025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413644573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:18:43.407083Z","caller":"traceutil/trace.go:171","msg":"trace[812326196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.413757506s","start":"2026-07-15T03:18:41.993311Z","end":"2026-07-15T03:18:43.407068Z","steps":["trace[812326196] 'agreement among raft nodes before linearized reading' (duration: 1.413564522s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.993286Z","time spent":"1.413827616s","remote":"127.0.0.1:44490","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-15T03:18:43.407225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460156465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:43.407258Z","caller":"traceutil/trace.go:171","msg":"trace[751491432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"1.460203426s","start":"2026-07-15T03:18:41.947047Z","end":"2026-07-15T03:18:43.40725Z","steps":["trace[751491432] 'agreement among raft nodes before linearized reading' (duration: 1.460150545s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.87657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:18:43.407279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.947038Z","time spent":"1.460236766s","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T03:18:43.407294Z","caller":"traceutil/trace.go:171","msg":"trace[111342774] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1087; }","duration":"544.946102ms","start":"2026-07-15T03:18:42.862338Z","end":"2026-07-15T03:18:43.407285Z","steps":["trace[111342774] 'agreement among raft nodes before linearized reading' (duration: 544.907541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:42.8622Z","time spent":"545.110945ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:43.407232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.489724ms","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-15T03:18:43.407385Z","caller":"traceutil/trace.go:171","msg":"trace[1078749171] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1087; }","duration":"225.702178ms","start":"2026-07-15T03:18:43.181671Z","end":"2026-07-15T03:18:43.407373Z","steps":["trace[1078749171] 'agreement among raft nodes before linearized reading' (duration: 225.539235ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:43.407365Z","caller":"traceutil/trace.go:171","msg":"trace[595251319] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.462666883s","start":"2026-07-15T03:18:41.944645Z","end":"2026-07-15T03:18:43.407312Z","steps":["trace[595251319] 'process raft request' (duration: 1.461876058s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.094808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T03:18:43.407547Z","caller":"traceutil/trace.go:171","msg":"trace[430021446] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1087; }","duration":"163.375622ms","start":"2026-07-15T03:18:43.244164Z","end":"2026-07-15T03:18:43.40754Z","steps":["trace[430021446] 'agreement among raft nodes before linearized reading' (duration: 163.018376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380205821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:43.407605Z","caller":"traceutil/trace.go:171","msg":"trace[1862412017] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1087; }","duration":"1.380267162s","start":"2026-07-15T03:18:42.02733Z","end":"2026-07-15T03:18:43.407598Z","steps":["trace[1862412017] 'agreement among raft nodes before linearized reading' (duration: 1.380225492s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:42.027315Z","time spent":"1.380323933s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:18:43.407631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:41.944638Z","time spent":"1.462868238s","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:43.407517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.928941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:43.407841Z","caller":"traceutil/trace.go:171","msg":"trace[1562425760] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1087; }","duration":"716.295139ms","start":"2026-07-15T03:18:42.691538Z","end":"2026-07-15T03:18:43.407834Z","steps":["trace[1562425760] 'agreement among raft nodes before linearized reading' (duration: 715.953171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:43.407874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:42.691514Z","time spent":"716.347449ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-15T03:18:44.043459Z","caller":"traceutil/trace.go:171","msg":"trace[624507854] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"100.022988ms","start":"2026-07-15T03:18:43.943372Z","end":"2026-07-15T03:18:44.043395Z","steps":["trace[624507854] 'process raft request' (duration: 99.964147ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:44.043803Z","caller":"traceutil/trace.go:171","msg":"trace[2139068351] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1129; }","duration":"627.064447ms","start":"2026-07-15T03:18:43.416725Z","end":"2026-07-15T03:18:44.043789Z","steps":["trace[2139068351] 'read index received' (duration: 70.90574ms)","trace[2139068351] 'applied index is now lower than readState.Index' (duration: 556.157137ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:44.043856Z","caller":"traceutil/trace.go:171","msg":"trace[1730886844] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"554.869003ms","start":"2026-07-15T03:18:43.488965Z","end":"2026-07-15T03:18:44.043834Z","steps":["trace[1730886844] 'process raft request' (duration: 554.317762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:44.044008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:43.488943Z","time spent":"554.984296ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:44.044016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.279371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:18:44.044063Z","caller":"traceutil/trace.go:171","msg":"trace[19372251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"627.383193ms","start":"2026-07-15T03:18:43.41667Z","end":"2026-07-15T03:18:44.044053Z","steps":["trace[19372251] 'agreement among raft nodes before linearized reading' (duration: 627.22071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:44.044102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:43.416649Z","time spent":"627.445674ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T03:18:44.043634Z","caller":"traceutil/trace.go:171","msg":"trace[20189088] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"630.147716ms","start":"2026-07-15T03:18:43.413473Z","end":"2026-07-15T03:18:44.043621Z","steps":["trace[20189088] 'process raft request' (duration: 601.496336ms)","trace[20189088] 'compare' (duration: 27.591589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:44.044407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:43.41345Z","time spent":"630.893439ms","remote":"127.0.0.1:44490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:18:44.044836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.816553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T03:18:44.045149Z","caller":"traceutil/trace.go:171","msg":"trace[1785543258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"102.197441ms","start":"2026-07-15T03:18:43.942925Z","end":"2026-07-15T03:18:44.045122Z","steps":["trace[1785543258] 'agreement among raft nodes before linearized reading' (duration: 101.068009ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:44.189887Z","caller":"traceutil/trace.go:171","msg":"trace[1551781280] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"139.167768ms","start":"2026-07-15T03:18:44.050694Z","end":"2026-07-15T03:18:44.189862Z","steps":["trace[1551781280] 'read index received' (duration: 129.235278ms)","trace[1551781280] 'applied index is now lower than readState.Index' (duration: 9.93152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:44.190118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.404042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-15T03:18:44.19019Z","caller":"traceutil/trace.go:171","msg":"trace[76900719] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1091; }","duration":"139.495275ms","start":"2026-07-15T03:18:44.050657Z","end":"2026-07-15T03:18:44.190153Z","steps":["trace[76900719] 'agreement among raft nodes before linearized reading' (duration: 139.302131ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:44.190151Z","caller":"traceutil/trace.go:171","msg":"trace[746367424] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"140.237019ms","start":"2026-07-15T03:18:44.049778Z","end":"2026-07-15T03:18:44.190015Z","steps":["trace[746367424] 'process raft request' (duration: 130.210057ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:46.173985Z","caller":"traceutil/trace.go:171","msg":"trace[659162824] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"119.822698ms","start":"2026-07-15T03:18:46.054134Z","end":"2026-07-15T03:18:46.173956Z","steps":["trace[659162824] 'process raft request' (duration: 90.941265ms)","trace[659162824] 'compare' (duration: 28.764941ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:03.959232Z","caller":"traceutil/trace.go:171","msg":"trace[1468453091] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"165.720579ms","start":"2026-07-15T03:19:03.793485Z","end":"2026-07-15T03:19:03.959206Z","steps":["trace[1468453091] 'process raft request' (duration: 165.564166ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:20.19197Z","caller":"traceutil/trace.go:171","msg":"trace[888965427] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"119.787257ms","start":"2026-07-15T03:19:20.07214Z","end":"2026-07-15T03:19:20.191927Z","steps":["trace[888965427] 'process raft request' (duration: 119.497202ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:20.488764Z","caller":"traceutil/trace.go:171","msg":"trace[531052421] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"200.118948ms","start":"2026-07-15T03:19:20.288612Z","end":"2026-07-15T03:19:20.488731Z","steps":["trace[531052421] 'process raft request' (duration: 111.515819ms)","trace[531052421] 'compare' (duration: 88.368444ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:34.470477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.893095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:19:34.470707Z","caller":"traceutil/trace.go:171","msg":"trace[674169302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"198.186562ms","start":"2026-07-15T03:19:34.272492Z","end":"2026-07-15T03:19:34.470678Z","steps":["trace[674169302] 'range keys from in-memory index tree' (duration: 197.808073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:34.470851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.155958ms","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-15T03:19:34.471016Z","caller":"traceutil/trace.go:171","msg":"trace[430389016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"214.381041ms","start":"2026-07-15T03:19:34.256612Z","end":"2026-07-15T03:19:34.470993Z","steps":["trace[430389016] 'range keys from in-memory index tree' (duration: 213.951723ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:34.687634Z","caller":"traceutil/trace.go:171","msg":"trace[1824315977] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"149.509237ms","start":"2026-07-15T03:19:34.538084Z","end":"2026-07-15T03:19:34.687594Z","steps":["trace[1824315977] 'process raft request' (duration: 118.88656ms)","trace[1824315977] 'compare' (duration: 30.405923ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:22:16.054594Z","caller":"traceutil/trace.go:171","msg":"trace[49915383] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"114.301337ms","start":"2026-07-15T03:22:15.940267Z","end":"2026-07-15T03:22:16.054569Z","steps":["trace[49915383] 'process raft request' (duration: 114.175134ms)"],"step_count":1}