{"level":"warn","ts":"2026-07-30T20:58:17.658955Z","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-30T20:58:17.65905Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-30T20:58:17.659125Z","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-30T20:58:17.659143Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-30T20:58:17.65917Z","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-30T20:58:17.659788Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-30T20:58:17.659858Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-30T20:58:17.663854Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.780162ms"} {"level":"info","ts":"2026-07-30T20:58:17.671177Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-30T20:58:17.671322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T20:58:17.671491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-30T20:58:17.671601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T20:58:17.671633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-30T20:58:17.671772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-30T20:58:17.678976Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T20:58:17.680446Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T20:58:17.681681Z","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-30T20:58:17.68428Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T20:58:17.684923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T20:58:17.685114Z","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-30T20:58:17.685231Z","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-30T20:58:17.68527Z","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-30T20:58:17.685733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-30T20:58:17.687592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-30T20:58:17.690217Z","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-30T20:58:17.690358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-30T20:58:17.690576Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-30T20:58:17.691363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T20:58:17.691425Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T20:58:17.772461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T20:58:17.772645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T20:58:17.772677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-30T20:58:17.772703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-30T20:58:17.772717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-30T20:58:17.773308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-30T20:58:17.773423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-30T20:58:17.775475Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:58:17.776661Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T20:58:17.776712Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:58:17.776664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:58:17.777043Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T20:58:17.777121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T20:58:17.777398Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:58:17.7775Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:58:17.777544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:58:17.780213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-07-30T20:58:17.780213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T20:58:28.404509Z","caller":"traceutil/trace.go:171","msg":"trace[303596790] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"101.897114ms","start":"2026-07-30T20:58:28.302592Z","end":"2026-07-30T20:58:28.404489Z","steps":["trace[303596790] 'process raft request' (duration: 101.588595ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:39.988643Z","caller":"traceutil/trace.go:171","msg":"trace[1357836775] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"133.935244ms","start":"2026-07-30T20:58:39.854685Z","end":"2026-07-30T20:58:39.98862Z","steps":["trace[1357836775] 'read index received' (duration: 122.010572ms)","trace[1357836775] 'applied index is now lower than readState.Index' (duration: 11.923492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:39.988858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.093398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:39.988905Z","caller":"traceutil/trace.go:171","msg":"trace[1114604865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"134.255883ms","start":"2026-07-30T20:58:39.854641Z","end":"2026-07-30T20:58:39.988897Z","steps":["trace[1114604865] 'agreement among raft nodes before linearized reading' (duration: 134.073008ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:39.988931Z","caller":"traceutil/trace.go:171","msg":"trace[1522725305] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"227.640163ms","start":"2026-07-30T20:58:39.761261Z","end":"2026-07-30T20:58:39.988901Z","steps":["trace[1522725305] 'process raft request' (duration: 215.441013ms)","trace[1522725305] 'compare' (duration: 11.646155ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:40.206985Z","caller":"traceutil/trace.go:171","msg":"trace[1100254484] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"120.370621ms","start":"2026-07-30T20:58:40.086568Z","end":"2026-07-30T20:58:40.206938Z","steps":["trace[1100254484] 'process raft request' (duration: 65.508134ms)","trace[1100254484] 'compare' (duration: 54.691352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:55.657908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.490099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:55.658037Z","caller":"traceutil/trace.go:171","msg":"trace[1056270570] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:484; }","duration":"474.623782ms","start":"2026-07-30T20:58:55.183385Z","end":"2026-07-30T20:58:55.658009Z","steps":["trace[1056270570] 'range keys from in-memory index tree' (duration: 474.415688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.657909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.343122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T20:58:55.658096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.18333Z","time spent":"474.750385ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-30T20:58:55.658144Z","caller":"traceutil/trace.go:171","msg":"trace[169637813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"698.584098ms","start":"2026-07-30T20:58:54.959504Z","end":"2026-07-30T20:58:55.658088Z","steps":["trace[169637813] 'range keys from in-memory index tree' (duration: 698.234831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.657925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.702857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:55.658242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:54.959483Z","time spent":"698.743052ms","remote":"127.0.0.1:33616","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-30T20:58:55.658305Z","caller":"traceutil/trace.go:171","msg":"trace[2003927819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"805.087556ms","start":"2026-07-30T20:58:54.853182Z","end":"2026-07-30T20:58:55.658269Z","steps":["trace[2003927819] 'range keys from in-memory index tree' (duration: 804.639796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.658404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:54.853162Z","time spent":"805.201888ms","remote":"127.0.0.1:33364","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-30T20:58:56.17314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054384556559425,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:58:56.227372Z","caller":"traceutil/trace.go:171","msg":"trace[1524341979] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"555.252595ms","start":"2026-07-30T20:58:55.672094Z","end":"2026-07-30T20:58:56.227347Z","steps":["trace[1524341979] 'read index received' (duration: 555.184464ms)","trace[1524341979] 'applied index is now lower than readState.Index' (duration: 66.231µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:56.227486Z","caller":"traceutil/trace.go:171","msg":"trace[1631745902] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"565.736446ms","start":"2026-07-30T20:58:55.661697Z","end":"2026-07-30T20:58:56.227434Z","steps":["trace[1631745902] 'process raft request' (duration: 565.507981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.227646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.526007ms","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-30T20:58:56.227645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.526558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T20:58:56.227689Z","caller":"traceutil/trace.go:171","msg":"trace[1569241788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"403.63052ms","start":"2026-07-30T20:58:55.824047Z","end":"2026-07-30T20:58:56.227678Z","steps":["trace[1569241788] 'agreement among raft nodes before linearized reading' (duration: 403.443725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.227744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.824023Z","time spent":"403.713281ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T20:58:56.227748Z","caller":"traceutil/trace.go:171","msg":"trace[1617531515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"169.678682ms","start":"2026-07-30T20:58:56.058047Z","end":"2026-07-30T20:58:56.227725Z","steps":["trace[1617531515] 'agreement among raft nodes before linearized reading' (duration: 169.492328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.227757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.591943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:56.227786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.720779ms","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-30T20:58:56.227857Z","caller":"traceutil/trace.go:171","msg":"trace[425081273] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:485; }","duration":"555.777897ms","start":"2026-07-30T20:58:55.672033Z","end":"2026-07-30T20:58:56.227811Z","steps":["trace[425081273] 'agreement among raft nodes before linearized reading' (duration: 555.49087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.227875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.910703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:56.227904Z","caller":"traceutil/trace.go:171","msg":"trace[383353417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"372.981215ms","start":"2026-07-30T20:58:55.854917Z","end":"2026-07-30T20:58:56.227898Z","steps":["trace[383353417] 'agreement among raft nodes before linearized reading' (duration: 372.938764ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:56.227893Z","caller":"traceutil/trace.go:171","msg":"trace[895093876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"342.855752ms","start":"2026-07-30T20:58:55.885Z","end":"2026-07-30T20:58:56.227856Z","steps":["trace[895093876] 'agreement among raft nodes before linearized reading' (duration: 342.678588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.227923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.854894Z","time spent":"373.025656ms","remote":"127.0.0.1:33348","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-30T20:58:56.227944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.884988Z","time spent":"342.946724ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T20:58:56.227948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.672007Z","time spent":"555.92548ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-30T20:58:56.228786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.661678Z","time spent":"565.929321ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:57.095614Z","caller":"traceutil/trace.go:171","msg":"trace[280995266] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"221.723472ms","start":"2026-07-30T20:58:56.873863Z","end":"2026-07-30T20:58:57.095586Z","steps":["trace[280995266] 'process raft request' (duration: 221.561378ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:57.10015Z","caller":"traceutil/trace.go:171","msg":"trace[217365917] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"215.570059ms","start":"2026-07-30T20:58:56.884567Z","end":"2026-07-30T20:58:57.100137Z","steps":["trace[217365917] 'process raft request' (duration: 215.481697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.750535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054384556559646,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:59:02.809907Z","caller":"traceutil/trace.go:171","msg":"trace[1764039702] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"559.950881ms","start":"2026-07-30T20:59:02.249934Z","end":"2026-07-30T20:59:02.809885Z","steps":["trace[1764039702] 'read index received' (duration: 559.170804ms)","trace[1764039702] 'applied index is now lower than readState.Index' (duration: 779.217µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:02.810015Z","caller":"traceutil/trace.go:171","msg":"trace[1361844322] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"625.698356ms","start":"2026-07-30T20:59:02.184306Z","end":"2026-07-30T20:59:02.810004Z","steps":["trace[1361844322] 'process raft request' (duration: 625.202255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.810093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:02.184282Z","time spent":"625.758208ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:59:02.810373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.426501ms","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-30T20:59:02.810449Z","caller":"traceutil/trace.go:171","msg":"trace[995933360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"560.548794ms","start":"2026-07-30T20:59:02.249885Z","end":"2026-07-30T20:59:02.810433Z","steps":["trace[995933360] 'agreement among raft nodes before linearized reading' (duration: 560.36483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.81037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.373883ms","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-30T20:59:02.810494Z","caller":"traceutil/trace.go:171","msg":"trace[1780368215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"517.540527ms","start":"2026-07-30T20:59:02.292937Z","end":"2026-07-30T20:59:02.810478Z","steps":["trace[1780368215] 'agreement among raft nodes before linearized reading' (duration: 517.313672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.81058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:02.292928Z","time spent":"517.644669ms","remote":"127.0.0.1:33616","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-30T20:59:02.810483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:02.249865Z","time spent":"560.611395ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T20:59:02.810782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.061715ms","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-30T20:59:02.810928Z","caller":"traceutil/trace.go:171","msg":"trace[682100383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"522.29306ms","start":"2026-07-30T20:59:02.28861Z","end":"2026-07-30T20:59:02.810903Z","steps":["trace[682100383] 'agreement among raft nodes before linearized reading' (duration: 521.675337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.811074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:02.288584Z","time spent":"522.475683ms","remote":"127.0.0.1:33616","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-30T20:59:02.81082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.821198ms","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-30T20:59:02.811461Z","caller":"traceutil/trace.go:171","msg":"trace[1695370008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"527.468242ms","start":"2026-07-30T20:59:02.283965Z","end":"2026-07-30T20:59:02.811434Z","steps":["trace[1695370008] 'agreement among raft nodes before linearized reading' (duration: 526.243866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:02.81152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:02.283955Z","time spent":"527.548894ms","remote":"127.0.0.1:33616","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-30T20:59:04.117468Z","caller":"traceutil/trace.go:171","msg":"trace[481850443] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"794.701466ms","start":"2026-07-30T20:59:03.322143Z","end":"2026-07-30T20:59:04.116845Z","steps":["trace[481850443] 'process raft request' (duration: 794.069613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:04.117667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:03.322117Z","time spent":"795.456972ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:59:04.131409Z","caller":"traceutil/trace.go:171","msg":"trace[1709435902] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"276.534996ms","start":"2026-07-30T20:59:03.854854Z","end":"2026-07-30T20:59:04.131389Z","steps":["trace[1709435902] 'read index received' (duration: 261.194116ms)","trace[1709435902] 'applied index is now lower than readState.Index' (duration: 15.340289ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:04.131638Z","caller":"traceutil/trace.go:171","msg":"trace[743317365] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"587.986409ms","start":"2026-07-30T20:59:03.543643Z","end":"2026-07-30T20:59:04.131629Z","steps":["trace[743317365] 'process raft request' (duration: 587.652442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:04.131713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:03.54362Z","time spent":"588.038049ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:59:04.1319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.012877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:59:04.131943Z","caller":"traceutil/trace.go:171","msg":"trace[1807485128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"277.124079ms","start":"2026-07-30T20:59:03.854808Z","end":"2026-07-30T20:59:04.131932Z","steps":["trace[1807485128] 'agreement among raft nodes before linearized reading' (duration: 277.025837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:04.131987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.243175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jv4bj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T20:59:04.132018Z","caller":"traceutil/trace.go:171","msg":"trace[1521695906] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jv4bj; range_end:; response_count:1; response_revision:587; }","duration":"242.315777ms","start":"2026-07-30T20:59:03.889694Z","end":"2026-07-30T20:59:04.132009Z","steps":["trace[1521695906] 'agreement among raft nodes before linearized reading' (duration: 242.223025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:05.319378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054384556559669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:59:05.819802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054384556559669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:59:06.067739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888621099s","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-30T20:59:06.067827Z","caller":"traceutil/trace.go:171","msg":"trace[1072506453] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"1.888706801s","start":"2026-07-30T20:59:04.179098Z","end":"2026-07-30T20:59:06.067805Z","steps":["trace[1072506453] 'range keys from in-memory index tree' (duration: 1.888609749s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.068454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.733638737s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:59:06.068543Z","caller":"traceutil/trace.go:171","msg":"trace[358643500] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.911988871s","start":"2026-07-30T20:59:04.15654Z","end":"2026-07-30T20:59:06.068529Z","steps":["trace[358643500] 'process raft request' (duration: 178.044857ms)","trace[358643500] 'compare' (duration: 1.733495094s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:59:06.068598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.156517Z","time spent":"1.912054572s","remote":"127.0.0.1:33526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:59:06.115629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.781039022s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:59:06.116025Z","caller":"traceutil/trace.go:171","msg":"trace[1814994352] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.933082979s","start":"2026-07-30T20:59:04.182919Z","end":"2026-07-30T20:59:06.116002Z","steps":["trace[1814994352] 'process raft request' (duration: 1.932970026s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.116132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.182902Z","time spent":"1.933173761s","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:59:06.116665Z","caller":"traceutil/trace.go:171","msg":"trace[1742107044] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:615; }","duration":"1.298198594s","start":"2026-07-30T20:59:04.818442Z","end":"2026-07-30T20:59:06.11664Z","steps":["trace[1742107044] 'read index received' (duration: 1.298190104s)","trace[1742107044] 'applied index is now lower than readState.Index' (duration: 6.83µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:59:06.116832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294754169s","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-30T20:59:06.116889Z","caller":"traceutil/trace.go:171","msg":"trace[1584064267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"1.294864532s","start":"2026-07-30T20:59:04.822009Z","end":"2026-07-30T20:59:06.116873Z","steps":["trace[1584064267] 'agreement among raft nodes before linearized reading' (duration: 1.294757879s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.116899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29149316s","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-30T20:59:06.116933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.821988Z","time spent":"1.294933513s","remote":"127.0.0.1:33616","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-30T20:59:06.116979Z","caller":"traceutil/trace.go:171","msg":"trace[424675749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.291628582s","start":"2026-07-30T20:59:04.825328Z","end":"2026-07-30T20:59:06.116957Z","steps":["trace[424675749] 'agreement among raft nodes before linearized reading' (duration: 1.29150607s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.117006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294224348s","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-30T20:59:06.117034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.825266Z","time spent":"1.291755825s","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T20:59:06.117053Z","caller":"traceutil/trace.go:171","msg":"trace[244013684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.294291279s","start":"2026-07-30T20:59:04.822748Z","end":"2026-07-30T20:59:06.117039Z","steps":["trace[244013684] 'agreement among raft nodes before linearized reading' (duration: 1.294126656s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.116978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261671448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:59:06.117106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.822737Z","time spent":"1.294357331s","remote":"127.0.0.1:33616","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-30T20:59:06.117252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298807986s","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-30T20:59:06.117273Z","caller":"traceutil/trace.go:171","msg":"trace[1146687031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.261902403s","start":"2026-07-30T20:59:04.85521Z","end":"2026-07-30T20:59:06.117112Z","steps":["trace[1146687031] 'agreement among raft nodes before linearized reading' (duration: 1.261593026s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:06.117314Z","caller":"traceutil/trace.go:171","msg":"trace[1916468283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"1.298913179s","start":"2026-07-30T20:59:04.818388Z","end":"2026-07-30T20:59:06.117301Z","steps":["trace[1916468283] 'agreement among raft nodes before linearized reading' (duration: 1.298314086s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:06.117386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.818369Z","time spent":"1.2990004s","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T20:59:06.117384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:04.855195Z","time spent":"1.262130188s","remote":"127.0.0.1:33348","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-30T20:59:06.675559Z","caller":"traceutil/trace.go:171","msg":"trace[578817052] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"151.132273ms","start":"2026-07-30T20:59:06.524404Z","end":"2026-07-30T20:59:06.675536Z","steps":["trace[578817052] 'process raft request' (duration: 150.620231ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:08.389769Z","caller":"traceutil/trace.go:171","msg":"trace[2053299780] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"259.771785ms","start":"2026-07-30T20:59:08.129965Z","end":"2026-07-30T20:59:08.389737Z","steps":["trace[2053299780] 'process raft request' (duration: 259.594672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:08.389927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.888977ms","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-30T20:59:08.390002Z","caller":"traceutil/trace.go:171","msg":"trace[812119654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"257.00678ms","start":"2026-07-30T20:59:08.132986Z","end":"2026-07-30T20:59:08.389993Z","steps":["trace[812119654] 'agreement among raft nodes before linearized reading' (duration: 256.820416ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:08.389763Z","caller":"traceutil/trace.go:171","msg":"trace[689481795] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"256.700272ms","start":"2026-07-30T20:59:08.133019Z","end":"2026-07-30T20:59:08.389719Z","steps":["trace[689481795] 'read index received' (duration: 256.531899ms)","trace[689481795] 'applied index is now lower than readState.Index' (duration: 167.323µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:59:08.398826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.005205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T20:59:08.398874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.094613ms","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-30T20:59:08.398945Z","caller":"traceutil/trace.go:171","msg":"trace[1741720401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"259.208575ms","start":"2026-07-30T20:59:08.139722Z","end":"2026-07-30T20:59:08.398931Z","steps":["trace[1741720401] 'agreement among raft nodes before linearized reading' (duration: 259.033632ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:08.398967Z","caller":"traceutil/trace.go:171","msg":"trace[923180238] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"259.343328ms","start":"2026-07-30T20:59:08.139605Z","end":"2026-07-30T20:59:08.398948Z","steps":["trace[923180238] 'process raft request' (duration: 258.695184ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:08.398891Z","caller":"traceutil/trace.go:171","msg":"trace[1253908703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"263.094106ms","start":"2026-07-30T20:59:08.135781Z","end":"2026-07-30T20:59:08.398875Z","steps":["trace[1253908703] 'agreement among raft nodes before linearized reading' (duration: 262.938243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:08.399018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.145308ms","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-30T20:59:08.399113Z","caller":"traceutil/trace.go:171","msg":"trace[332226519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"263.25808ms","start":"2026-07-30T20:59:08.135832Z","end":"2026-07-30T20:59:08.39909Z","steps":["trace[332226519] 'agreement among raft nodes before linearized reading' (duration: 262.995894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:11.261772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.333479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-30T20:59:11.261949Z","caller":"traceutil/trace.go:171","msg":"trace[895721314] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:677; }","duration":"134.590494ms","start":"2026-07-30T20:59:11.127302Z","end":"2026-07-30T20:59:11.261893Z","steps":["trace[895721314] 'range keys from in-memory index tree' (duration: 133.948121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.307704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.484746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T20:59:22.307809Z","caller":"traceutil/trace.go:171","msg":"trace[2093030652] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:762; }","duration":"609.610118ms","start":"2026-07-30T20:59:21.698177Z","end":"2026-07-30T20:59:22.307788Z","steps":["trace[2093030652] 'count revisions from in-memory index tree' (duration: 609.371394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.307863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:21.69812Z","time spent":"609.7263ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:59:22.308136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.541356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:59:22.308238Z","caller":"traceutil/trace.go:171","msg":"trace[1795436019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"452.670518ms","start":"2026-07-30T20:59:21.855548Z","end":"2026-07-30T20:59:22.308218Z","steps":["trace[1795436019] 'range keys from in-memory index tree' (duration: 452.483394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.308286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:21.855533Z","time spent":"452.740329ms","remote":"127.0.0.1:33348","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-30T20:59:22.308234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.807212ms","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":"warn","ts":"2026-07-30T20:59:22.308313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.069871ms","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-30T20:59:22.308357Z","caller":"traceutil/trace.go:171","msg":"trace[1132817562] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:762; }","duration":"401.992215ms","start":"2026-07-30T20:59:21.906344Z","end":"2026-07-30T20:59:22.308336Z","steps":["trace[1132817562] 'count revisions from in-memory index tree' (duration: 401.627379ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:22.308369Z","caller":"traceutil/trace.go:171","msg":"trace[2107098220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"520.168793ms","start":"2026-07-30T20:59:21.788188Z","end":"2026-07-30T20:59:22.308356Z","steps":["trace[2107098220] 'range keys from in-memory index tree' (duration: 519.913828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.308398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:21.78817Z","time spent":"520.223044ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T20:59:22.308408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:21.906323Z","time spent":"402.071908ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:59:22.568196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054384556560006,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:59:22.780828Z","caller":"traceutil/trace.go:171","msg":"trace[964464276] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"768.593244ms","start":"2026-07-30T20:59:22.012203Z","end":"2026-07-30T20:59:22.780796Z","steps":["trace[964464276] 'process raft request' (duration: 768.432621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.781076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.012181Z","time spent":"768.751667ms","remote":"127.0.0.1:33586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:59:22.832113Z","caller":"traceutil/trace.go:171","msg":"trace[1278381379] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"764.289711ms","start":"2026-07-30T20:59:22.067797Z","end":"2026-07-30T20:59:22.832087Z","steps":["trace[1278381379] 'read index received' (duration: 712.80786ms)","trace[1278381379] 'applied index is now lower than readState.Index' (duration: 51.480971ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:22.832134Z","caller":"traceutil/trace.go:171","msg":"trace[2016860344] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"519.365632ms","start":"2026-07-30T20:59:22.312746Z","end":"2026-07-30T20:59:22.832112Z","steps":["trace[2016860344] 'process raft request' (duration: 517.154399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.832276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.312722Z","time spent":"519.475093ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:59:22.832363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.558797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T20:59:22.832445Z","caller":"traceutil/trace.go:171","msg":"trace[1471204918] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:764; }","duration":"764.696778ms","start":"2026-07-30T20:59:22.067731Z","end":"2026-07-30T20:59:22.832428Z","steps":["trace[1471204918] 'agreement among raft nodes before linearized reading' (duration: 764.482134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.832503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.06771Z","time spent":"764.776081ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:59:22.832495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.796073ms","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-30T20:59:22.832514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.305057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:59:22.832635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.613969ms","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":"warn","ts":"2026-07-30T20:59:22.83268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.122311ms","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-30T20:59:22.832705Z","caller":"traceutil/trace.go:171","msg":"trace[1517341024] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:764; }","duration":"313.757191ms","start":"2026-07-30T20:59:22.51893Z","end":"2026-07-30T20:59:22.832687Z","steps":["trace[1517341024] 'agreement among raft nodes before linearized reading' (duration: 313.596878ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:22.832711Z","caller":"traceutil/trace.go:171","msg":"trace[545241407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"355.177172ms","start":"2026-07-30T20:59:22.477524Z","end":"2026-07-30T20:59:22.832701Z","steps":["trace[545241407] 'agreement among raft nodes before linearized reading' (duration: 355.07254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.832747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.089819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T20:59:22.832769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.477513Z","time spent":"355.245054ms","remote":"127.0.0.1:33616","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-30T20:59:22.832808Z","caller":"traceutil/trace.go:171","msg":"trace[2021948072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"366.198161ms","start":"2026-07-30T20:59:22.466596Z","end":"2026-07-30T20:59:22.832795Z","steps":["trace[2021948072] 'agreement among raft nodes before linearized reading' (duration: 365.993968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.832839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.466568Z","time spent":"366.265583ms","remote":"127.0.0.1:33616","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-30T20:59:22.83263Z","caller":"traceutil/trace.go:171","msg":"trace[1908632880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"523.418659ms","start":"2026-07-30T20:59:22.309184Z","end":"2026-07-30T20:59:22.832603Z","steps":["trace[1908632880] 'agreement among raft nodes before linearized reading' (duration: 523.116813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.832888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.309174Z","time spent":"523.696735ms","remote":"127.0.0.1:33364","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-30T20:59:22.832762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.51891Z","time spent":"313.836703ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:59:22.832572Z","caller":"traceutil/trace.go:171","msg":"trace[532178930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"355.935726ms","start":"2026-07-30T20:59:22.476616Z","end":"2026-07-30T20:59:22.832552Z","steps":["trace[532178930] 'agreement among raft nodes before linearized reading' (duration: 355.708632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.833155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.476592Z","time spent":"356.554328ms","remote":"127.0.0.1:33616","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-30T20:59:22.833375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.240887ms","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-30T20:59:22.83344Z","caller":"traceutil/trace.go:171","msg":"trace[1189983767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"360.333649ms","start":"2026-07-30T20:59:22.473085Z","end":"2026-07-30T20:59:22.833418Z","steps":["trace[1189983767] 'agreement among raft nodes before linearized reading' (duration: 359.726188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:22.833493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:22.473061Z","time spent":"360.41903ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T20:59:27.712707Z","caller":"traceutil/trace.go:171","msg":"trace[1329385311] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"135.475376ms","start":"2026-07-30T20:59:27.577213Z","end":"2026-07-30T20:59:27.712689Z","steps":["trace[1329385311] 'process raft request' (duration: 135.345774ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:29.050497Z","caller":"traceutil/trace.go:171","msg":"trace[924017668] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"104.12474ms","start":"2026-07-30T20:59:28.946349Z","end":"2026-07-30T20:59:29.050474Z","steps":["trace[924017668] 'process raft request' (duration: 104.08346ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:29.051014Z","caller":"traceutil/trace.go:171","msg":"trace[1538311273] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"105.267882ms","start":"2026-07-30T20:59:28.945729Z","end":"2026-07-30T20:59:29.050997Z","steps":["trace[1538311273] 'process raft request' (duration: 104.425387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:29.051176Z","caller":"traceutil/trace.go:171","msg":"trace[1316725280] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"104.863304ms","start":"2026-07-30T20:59:28.9463Z","end":"2026-07-30T20:59:29.051164Z","steps":["trace[1316725280] 'process raft request' (duration: 104.09245ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:43.569541Z","caller":"traceutil/trace.go:171","msg":"trace[1029487108] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"124.777329ms","start":"2026-07-30T20:59:43.444727Z","end":"2026-07-30T20:59:43.569504Z","steps":["trace[1029487108] 'process raft request' (duration: 77.829892ms)","trace[1029487108] 'compare' (duration: 46.727143ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:53.258627Z","caller":"traceutil/trace.go:171","msg":"trace[1669699048] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"161.961934ms","start":"2026-07-30T20:59:53.096643Z","end":"2026-07-30T20:59:53.258605Z","steps":["trace[1669699048] 'read index received' (duration: 161.740241ms)","trace[1669699048] 'applied index is now lower than readState.Index' (duration: 220.713µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:53.258683Z","caller":"traceutil/trace.go:171","msg":"trace[1888017545] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"193.901412ms","start":"2026-07-30T20:59:53.064749Z","end":"2026-07-30T20:59:53.258651Z","steps":["trace[1888017545] 'process raft request' (duration: 193.711489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:53.258887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.220768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-30T20:59:53.258948Z","caller":"traceutil/trace.go:171","msg":"trace[412269186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"162.33407ms","start":"2026-07-30T20:59:53.096598Z","end":"2026-07-30T20:59:53.258933Z","steps":["trace[412269186] 'agreement among raft nodes before linearized reading' (duration: 162.161728ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:57.387991Z","caller":"traceutil/trace.go:171","msg":"trace[318350526] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"112.096336ms","start":"2026-07-30T20:59:57.275682Z","end":"2026-07-30T20:59:57.387778Z","steps":["trace[318350526] 'process raft request' (duration: 111.879482ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:57.69533Z","caller":"traceutil/trace.go:171","msg":"trace[656957508] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"301.570173ms","start":"2026-07-30T20:59:57.393736Z","end":"2026-07-30T20:59:57.695307Z","steps":["trace[656957508] 'process raft request' (duration: 301.520162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:57.695456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:57.393715Z","time spent":"301.673765ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:59:57.695687Z","caller":"traceutil/trace.go:171","msg":"trace[878051519] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"302.220253ms","start":"2026-07-30T20:59:57.393456Z","end":"2026-07-30T20:59:57.695677Z","steps":["trace[878051519] 'process raft request' (duration: 301.761726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:57.695771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:57.393436Z","time spent":"302.306974ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:59:57.695511Z","caller":"traceutil/trace.go:171","msg":"trace[1656962870] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"302.739601ms","start":"2026-07-30T20:59:57.392719Z","end":"2026-07-30T20:59:57.695458Z","steps":["trace[1656962870] 'process raft request' (duration: 302.182442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:57.697076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:57.392696Z","time spent":"304.157095ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:00:07.975843Z","caller":"traceutil/trace.go:171","msg":"trace[1810209482] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"489.484111ms","start":"2026-07-30T21:00:07.48632Z","end":"2026-07-30T21:00:07.975804Z","steps":["trace[1810209482] 'read index received' (duration: 489.457721ms)","trace[1810209482] 'applied index is now lower than readState.Index' (duration: 25.12µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:07.975867Z","caller":"traceutil/trace.go:171","msg":"trace[653169462] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"540.690915ms","start":"2026-07-30T21:00:07.435144Z","end":"2026-07-30T21:00:07.975835Z","steps":["trace[653169462] 'process raft request' (duration: 540.501112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:07.976093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.738995ms","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-30T21:00:07.976134Z","caller":"traceutil/trace.go:171","msg":"trace[678355678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"489.851807ms","start":"2026-07-30T21:00:07.486272Z","end":"2026-07-30T21:00:07.976123Z","steps":["trace[678355678] 'agreement among raft nodes before linearized reading' (duration: 489.668334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:07.976172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.48625Z","time spent":"489.912587ms","remote":"127.0.0.1:33616","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-30T21:00:07.976222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.435119Z","time spent":"540.816086ms","remote":"127.0.0.1:33616","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-30T21:00:08.05539Z","caller":"traceutil/trace.go:171","msg":"trace[1565322868] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"328.749657ms","start":"2026-07-30T21:00:07.726623Z","end":"2026-07-30T21:00:08.055373Z","steps":["trace[1565322868] 'process raft request' (duration: 326.850099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:08.055493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.726587Z","time spent":"328.851119ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:00:08.055696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.485255ms","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-30T21:00:08.055789Z","caller":"traceutil/trace.go:171","msg":"trace[1516101316] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1043; }","duration":"434.619488ms","start":"2026-07-30T21:00:07.621146Z","end":"2026-07-30T21:00:08.055765Z","steps":["trace[1516101316] 'agreement among raft nodes before linearized reading' (duration: 434.455896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:08.055832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.711369ms","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-30T21:00:08.055857Z","caller":"traceutil/trace.go:171","msg":"trace[2109322898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"317.756539ms","start":"2026-07-30T21:00:07.738093Z","end":"2026-07-30T21:00:08.05585Z","steps":["trace[2109322898] 'agreement among raft nodes before linearized reading' (duration: 317.668348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:08.055862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.621132Z","time spent":"434.711709ms","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"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-30T21:00:08.055932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.485186ms","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-30T21:00:08.055963Z","caller":"traceutil/trace.go:171","msg":"trace[390897937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"315.546876ms","start":"2026-07-30T21:00:07.740412Z","end":"2026-07-30T21:00:08.055959Z","steps":["trace[390897937] 'agreement among raft nodes before linearized reading' (duration: 315.482895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:08.055996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.740394Z","time spent":"315.597727ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:00:08.055883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.738086Z","time spent":"317.79261ms","remote":"127.0.0.1:33616","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-30T21:00:08.056232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.159706ms","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-30T21:00:08.056256Z","caller":"traceutil/trace.go:171","msg":"trace[1315998515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"318.224697ms","start":"2026-07-30T21:00:07.738026Z","end":"2026-07-30T21:00:08.056251Z","steps":["trace[1315998515] 'agreement among raft nodes before linearized reading' (duration: 318.168336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:08.056274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:07.738004Z","time spent":"318.266538ms","remote":"127.0.0.1:33616","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-30T21:00:10.088867Z","caller":"traceutil/trace.go:171","msg":"trace[189383235] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"103.438947ms","start":"2026-07-30T21:00:09.985402Z","end":"2026-07-30T21:00:10.088841Z","steps":["trace[189383235] 'process raft request' (duration: 103.224073ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:10.270983Z","caller":"traceutil/trace.go:171","msg":"trace[1200063243] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"109.564368ms","start":"2026-07-30T21:00:10.161396Z","end":"2026-07-30T21:00:10.27096Z","steps":["trace[1200063243] 'process raft request' (duration: 109.522578ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:10.2711Z","caller":"traceutil/trace.go:171","msg":"trace[169774607] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"110.181317ms","start":"2026-07-30T21:00:10.160898Z","end":"2026-07-30T21:00:10.271079Z","steps":["trace[169774607] 'process raft request' (duration: 108.811697ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:10.2713Z","caller":"traceutil/trace.go:171","msg":"trace[1371496523] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"110.095466ms","start":"2026-07-30T21:00:10.161193Z","end":"2026-07-30T21:00:10.271289Z","steps":["trace[1371496523] 'process raft request' (duration: 109.67232ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:12.302085Z","caller":"traceutil/trace.go:171","msg":"trace[135329467] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"116.363128ms","start":"2026-07-30T21:00:12.185699Z","end":"2026-07-30T21:00:12.302062Z","steps":["trace[135329467] 'process raft request' (duration: 79.464564ms)","trace[135329467] 'compare' (duration: 36.494848ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:12.497064Z","caller":"traceutil/trace.go:171","msg":"trace[1822033643] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"191.144622ms","start":"2026-07-30T21:00:12.305887Z","end":"2026-07-30T21:00:12.497032Z","steps":["trace[1822033643] 'process raft request' (duration: 163.76996ms)","trace[1822033643] 'compare' (duration: 27.26232ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:12.548844Z","caller":"traceutil/trace.go:171","msg":"trace[497687157] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"242.541985ms","start":"2026-07-30T21:00:12.306204Z","end":"2026-07-30T21:00:12.548746Z","steps":["trace[497687157] 'process raft request' (duration: 242.367072ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:12.5489Z","caller":"traceutil/trace.go:171","msg":"trace[1926472206] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"239.867494ms","start":"2026-07-30T21:00:12.309008Z","end":"2026-07-30T21:00:12.548876Z","steps":["trace[1926472206] 'process raft request' (duration: 239.691401ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:14.683332Z","caller":"traceutil/trace.go:171","msg":"trace[1127831848] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"130.797831ms","start":"2026-07-30T21:00:14.552514Z","end":"2026-07-30T21:00:14.683312Z","steps":["trace[1127831848] 'read index received' (duration: 130.68271ms)","trace[1127831848] 'applied index is now lower than readState.Index' (duration: 114.201µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:14.683433Z","caller":"traceutil/trace.go:171","msg":"trace[1640539684] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"179.072022ms","start":"2026-07-30T21:00:14.504322Z","end":"2026-07-30T21:00:14.683394Z","steps":["trace[1640539684] 'process raft request' (duration: 178.871039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:14.683518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.969794ms","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-30T21:00:14.683548Z","caller":"traceutil/trace.go:171","msg":"trace[638374750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"131.057644ms","start":"2026-07-30T21:00:14.552482Z","end":"2026-07-30T21:00:14.683539Z","steps":["trace[638374750] 'agreement among raft nodes before linearized reading' (duration: 130.921392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:14.683689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.468531ms","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-30T21:00:14.683841Z","caller":"traceutil/trace.go:171","msg":"trace[914334124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"129.626285ms","start":"2026-07-30T21:00:14.554191Z","end":"2026-07-30T21:00:14.683817Z","steps":["trace[914334124] 'agreement among raft nodes before linearized reading' (duration: 129.411221ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:14.872327Z","caller":"traceutil/trace.go:171","msg":"trace[124352831] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"185.214524ms","start":"2026-07-30T21:00:14.687076Z","end":"2026-07-30T21:00:14.872291Z","steps":["trace[124352831] 'process raft request' (duration: 135.299809ms)","trace[124352831] 'compare' (duration: 49.680571ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:16.298708Z","caller":"traceutil/trace.go:171","msg":"trace[511834782] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"120.821301ms","start":"2026-07-30T21:00:16.177857Z","end":"2026-07-30T21:00:16.298678Z","steps":["trace[511834782] 'process raft request' (duration: 89.899272ms)","trace[511834782] 'compare' (duration: 30.782207ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:16.5316Z","caller":"traceutil/trace.go:171","msg":"trace[481729260] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"229.209118ms","start":"2026-07-30T21:00:16.30237Z","end":"2026-07-30T21:00:16.531579Z","steps":["trace[481729260] 'process raft request' (duration: 182.16543ms)","trace[481729260] 'compare' (duration: 46.949616ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:59.203129Z","caller":"traceutil/trace.go:171","msg":"trace[834712428] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"101.130712ms","start":"2026-07-30T21:00:59.10197Z","end":"2026-07-30T21:00:59.203101Z","steps":["trace[834712428] 'process raft request' (duration: 100.94387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:12.578423Z","caller":"traceutil/trace.go:171","msg":"trace[1981171579] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"222.377764ms","start":"2026-07-30T21:01:12.356012Z","end":"2026-07-30T21:01:12.57839Z","steps":["trace[1981171579] 'process raft request' (duration: 222.154121ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:20.81795Z","caller":"traceutil/trace.go:171","msg":"trace[2065786598] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"192.739548ms","start":"2026-07-30T21:01:20.62519Z","end":"2026-07-30T21:01:20.81793Z","steps":["trace[2065786598] 'read index received' (duration: 192.506395ms)","trace[2065786598] 'applied index is now lower than readState.Index' (duration: 232.363µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:20.818125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.918181ms","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-30T21:01:20.818153Z","caller":"traceutil/trace.go:171","msg":"trace[987880105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"193.008043ms","start":"2026-07-30T21:01:20.625137Z","end":"2026-07-30T21:01:20.818145Z","steps":["trace[987880105] 'agreement among raft nodes before linearized reading' (duration: 192.88195ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:20.818292Z","caller":"traceutil/trace.go:171","msg":"trace[1768619483] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"211.666392ms","start":"2026-07-30T21:01:20.606594Z","end":"2026-07-30T21:01:20.81826Z","steps":["trace[1768619483] 'process raft request' (duration: 211.203896ms)"],"step_count":1}