{"level":"warn","ts":"2026-07-10T14:45:12.789926Z","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-10T14:45:12.790019Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.175:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.175:2380","--initial-cluster=instance=https://199.19.213.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.175: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-10T14:45:12.790125Z","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-10T14:45:12.790139Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-07-10T14:45:12.790172Z","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-10T14:45:12.790811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"]} {"level":"info","ts":"2026-07-10T14:45:12.790914Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.175: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-10T14:45:12.796804Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.648494ms"} {"level":"info","ts":"2026-07-10T14:45:12.805267Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"223c7693b78843e1","cluster-id":"b697af90308fdc01"} {"level":"info","ts":"2026-07-10T14:45:12.805364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-10T14:45:12.805389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 0"} {"level":"info","ts":"2026-07-10T14:45:12.805403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 223c7693b78843e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-10T14:45:12.805439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 1"} {"level":"info","ts":"2026-07-10T14:45:12.805494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"warn","ts":"2026-07-10T14:45:12.813609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-10T14:45:12.818252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-10T14:45:12.819511Z","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-10T14:45:12.821661Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"223c7693b78843e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-10T14:45:12.82182Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"223c7693b78843e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-10T14:45:12.82422Z","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-10T14:45:12.8245Z","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-10T14:45:12.82457Z","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-10T14:45:12.825085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"info","ts":"2026-07-10T14:45:12.825565Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","added-peer-id":"223c7693b78843e1","added-peer-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-07-10T14:45:12.829003Z","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-10T14:45:12.82937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-07-10T14:45:12.830268Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-07-10T14:45:12.83056Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"223c7693b78843e1","initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-10T14:45:12.830619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-10T14:45:13.506185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-10T14:45:13.506255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-10T14:45:13.506306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgPreVoteResp from 223c7693b78843e1 at term 1"} {"level":"info","ts":"2026-07-10T14:45:13.50634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became candidate at term 2"} {"level":"info","ts":"2026-07-10T14:45:13.506352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgVoteResp from 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-07-10T14:45:13.506368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became leader at term 2"} {"level":"info","ts":"2026-07-10T14:45:13.506386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 223c7693b78843e1 elected leader 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-07-10T14:45:13.50784Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"223c7693b78843e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.175:2379]}","request-path":"/0/members/223c7693b78843e1/attributes","cluster-id":"b697af90308fdc01","publish-timeout":"7s"} {"level":"info","ts":"2026-07-10T14:45:13.507873Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T14:45:13.507995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-10T14:45:13.508039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-10T14:45:13.508555Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-10T14:45:13.508613Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-10T14:45:13.50964Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T14:45:13.509767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T14:45:13.509802Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T14:45:13.511715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.175:2379"} {"level":"info","ts":"2026-07-10T14:45:13.513021Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-10T14:45:22.717731Z","caller":"traceutil/trace.go:171","msg":"trace[356270065] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"129.999983ms","start":"2026-07-10T14:45:22.587703Z","end":"2026-07-10T14:45:22.717703Z","steps":["trace[356270065] 'read index received' (duration: 129.820026ms)","trace[356270065] 'applied index is now lower than readState.Index' (duration: 178.997µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:45:22.718046Z","caller":"traceutil/trace.go:171","msg":"trace[275832666] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"187.844356ms","start":"2026-07-10T14:45:22.530169Z","end":"2026-07-10T14:45:22.718014Z","steps":["trace[275832666] 'process raft request' (duration: 187.402709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:22.718072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.17982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:45:22.718159Z","caller":"traceutil/trace.go:171","msg":"trace[690816593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:334; }","duration":"130.44564ms","start":"2026-07-10T14:45:22.587698Z","end":"2026-07-10T14:45:22.718143Z","steps":["trace[690816593] 'agreement among raft nodes before linearized reading' (duration: 130.132098ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:25.716072Z","caller":"traceutil/trace.go:171","msg":"trace[1454044917] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"116.135628ms","start":"2026-07-10T14:45:25.599907Z","end":"2026-07-10T14:45:25.716043Z","steps":["trace[1454044917] 'process raft request' (duration: 115.95912ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:37.551004Z","caller":"traceutil/trace.go:171","msg":"trace[1014870851] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"443.188182ms","start":"2026-07-10T14:45:37.107792Z","end":"2026-07-10T14:45:37.550981Z","steps":["trace[1014870851] 'read index received' (duration: 442.978632ms)","trace[1014870851] 'applied index is now lower than readState.Index' (duration: 208.37µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:45:37.551075Z","caller":"traceutil/trace.go:171","msg":"trace[1673601183] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"697.548696ms","start":"2026-07-10T14:45:36.8535Z","end":"2026-07-10T14:45:37.551049Z","steps":["trace[1673601183] 'process raft request' (duration: 697.263052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.551216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.256262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-10T14:45:37.551404Z","caller":"traceutil/trace.go:171","msg":"trace[475274118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"335.399718ms","start":"2026-07-10T14:45:37.215908Z","end":"2026-07-10T14:45:37.551308Z","steps":["trace[475274118] 'agreement among raft nodes before linearized reading' (duration: 335.246981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.551658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:37.215893Z","time spent":"335.594818ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-10T14:45:37.551699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.775571ms","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-10T14:45:37.551834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:36.853479Z","time spent":"697.665001ms","remote":"127.0.0.1:51674","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-10T14:45:37.55192Z","caller":"traceutil/trace.go:171","msg":"trace[1512448868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"444.101537ms","start":"2026-07-10T14:45:37.107735Z","end":"2026-07-10T14:45:37.551837Z","steps":["trace[1512448868] 'agreement among raft nodes before linearized reading' (duration: 443.359371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.552014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:37.107713Z","time spent":"444.286106ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-10T14:45:37.888748Z","caller":"traceutil/trace.go:171","msg":"trace[235888090] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"327.359345ms","start":"2026-07-10T14:45:37.56136Z","end":"2026-07-10T14:45:37.888719Z","steps":["trace[235888090] 'read index received' (duration: 327.196387ms)","trace[235888090] 'applied index is now lower than readState.Index' (duration: 161.968µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:45:37.888811Z","caller":"traceutil/trace.go:171","msg":"trace[1850506097] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"334.021011ms","start":"2026-07-10T14:45:37.554758Z","end":"2026-07-10T14:45:37.888779Z","steps":["trace[1850506097] 'process raft request' (duration: 333.754768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.888961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:37.554733Z","time spent":"334.134837ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T14:45:37.88913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.719103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-10T14:45:37.889263Z","caller":"traceutil/trace.go:171","msg":"trace[1208086600] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:429; }","duration":"327.898942ms","start":"2026-07-10T14:45:37.561331Z","end":"2026-07-10T14:45:37.88923Z","steps":["trace[1208086600] 'agreement among raft nodes before linearized reading' (duration: 327.521972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.889335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:37.561316Z","time spent":"328.007747ms","remote":"127.0.0.1:51886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-10T14:45:37.897881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.692784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:45:37.897878Z","caller":"traceutil/trace.go:171","msg":"trace[3583787] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"334.780149ms","start":"2026-07-10T14:45:37.563063Z","end":"2026-07-10T14:45:37.897843Z","steps":["trace[3583787] 'process raft request' (duration: 334.614931ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:37.897951Z","caller":"traceutil/trace.go:171","msg":"trace[1933242765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"141.780138ms","start":"2026-07-10T14:45:37.756155Z","end":"2026-07-10T14:45:37.897935Z","steps":["trace[1933242765] 'agreement among raft nodes before linearized reading' (duration: 141.647141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:37.898112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:37.563038Z","time spent":"334.968448ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:45:48.217049Z","caller":"traceutil/trace.go:171","msg":"trace[817032875] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"155.96556ms","start":"2026-07-10T14:45:48.061051Z","end":"2026-07-10T14:45:48.217017Z","steps":["trace[817032875] 'read index received' (duration: 149.098813ms)","trace[817032875] 'applied index is now lower than readState.Index' (duration: 6.865256ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:45:48.217165Z","caller":"traceutil/trace.go:171","msg":"trace[1636295274] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"192.930678ms","start":"2026-07-10T14:45:48.024185Z","end":"2026-07-10T14:45:48.217116Z","steps":["trace[1636295274] 'process raft request' (duration: 186.007538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:48.218421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.338675ms","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-10T14:45:48.222602Z","caller":"traceutil/trace.go:171","msg":"trace[1495289265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"161.605989ms","start":"2026-07-10T14:45:48.06094Z","end":"2026-07-10T14:45:48.222546Z","steps":["trace[1495289265] 'agreement among raft nodes before linearized reading' (duration: 156.190962ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:54.23584Z","caller":"traceutil/trace.go:171","msg":"trace[101965646] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"578.977108ms","start":"2026-07-10T14:45:53.656835Z","end":"2026-07-10T14:45:54.235812Z","steps":["trace[101965646] 'process raft request' (duration: 578.573354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.236133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.656807Z","time spent":"579.124146ms","remote":"127.0.0.1:51674","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-10T14:45:54.50724Z","caller":"traceutil/trace.go:171","msg":"trace[295272564] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"772.626008ms","start":"2026-07-10T14:45:53.734578Z","end":"2026-07-10T14:45:54.507204Z","steps":["trace[295272564] 'process raft request' (duration: 772.13194ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:54.507325Z","caller":"traceutil/trace.go:171","msg":"trace[1365419437] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:550; }","duration":"751.594985ms","start":"2026-07-10T14:45:53.755699Z","end":"2026-07-10T14:45:54.507294Z","steps":["trace[1365419437] 'read index received' (duration: 479.963148ms)","trace[1365419437] 'applied index is now lower than readState.Index' (duration: 271.631017ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:45:54.507424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.734561Z","time spent":"772.781808ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507474Z","caller":"traceutil/trace.go:171","msg":"trace[1447610885] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"771.077741ms","start":"2026-07-10T14:45:53.736388Z","end":"2026-07-10T14:45:54.507466Z","steps":["trace[1447610885] 'process raft request' (duration: 770.456274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.507566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.73638Z","time spent":"771.122414ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507616Z","caller":"traceutil/trace.go:171","msg":"trace[824438465] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"783.098169ms","start":"2026-07-10T14:45:53.724508Z","end":"2026-07-10T14:45:54.507606Z","steps":["trace[824438465] 'process raft request' (duration: 782.046418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.507667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.72449Z","time spent":"783.154812ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507747Z","caller":"traceutil/trace.go:171","msg":"trace[1751607855] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"783.181874ms","start":"2026-07-10T14:45:53.724556Z","end":"2026-07-10T14:45:54.507738Z","steps":["trace[1751607855] 'process raft request' (duration: 782.040567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.507791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.724553Z","time spent":"783.218337ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507883Z","caller":"traceutil/trace.go:171","msg":"trace[243878276] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"776.4841ms","start":"2026-07-10T14:45:53.73139Z","end":"2026-07-10T14:45:54.507874Z","steps":["trace[243878276] 'process raft request' (duration: 775.240437ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:54.507863Z","caller":"traceutil/trace.go:171","msg":"trace[207550150] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"756.468144ms","start":"2026-07-10T14:45:53.751349Z","end":"2026-07-10T14:45:54.507817Z","steps":["trace[207550150] 'process raft request' (duration: 755.650526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.50793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.731377Z","time spent":"776.534243ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507959Z","caller":"traceutil/trace.go:171","msg":"trace[1490799693] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"594.695912ms","start":"2026-07-10T14:45:53.913253Z","end":"2026-07-10T14:45:54.507949Z","steps":["trace[1490799693] 'process raft request' (duration: 593.966649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.507999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.751338Z","time spent":"756.598301ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.507445Z","caller":"traceutil/trace.go:171","msg":"trace[1147014455] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"772.321562ms","start":"2026-07-10T14:45:53.735114Z","end":"2026-07-10T14:45:54.507436Z","steps":["trace[1147014455] 'process raft request' (duration: 771.690235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.38434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-10T14:45:54.508107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.735096Z","time spent":"772.989791ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.508164Z","caller":"traceutil/trace.go:171","msg":"trace[1724477548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"752.488277ms","start":"2026-07-10T14:45:53.755667Z","end":"2026-07-10T14:45:54.508155Z","steps":["trace[1724477548] 'agreement among raft nodes before linearized reading' (duration: 752.36519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.755658Z","time spent":"752.528358ms","remote":"127.0.0.1:51356","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-10T14:45:54.508251Z","caller":"traceutil/trace.go:171","msg":"trace[247067326] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"746.707726ms","start":"2026-07-10T14:45:53.761512Z","end":"2026-07-10T14:45:54.50822Z","steps":["trace[247067326] 'process raft request' (duration: 745.537718ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:54.50827Z","caller":"traceutil/trace.go:171","msg":"trace[1207599537] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"771.605462ms","start":"2026-07-10T14:45:53.736652Z","end":"2026-07-10T14:45:54.508257Z","steps":["trace[1207599537] 'process raft request' (duration: 770.228672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.761484Z","time spent":"746.793951ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-10T14:45:54.508324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.736643Z","time spent":"771.656525ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-10T14:45:54.508355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.152012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-10T14:45:54.50801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.913221Z","time spent":"594.764566ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:45:54.508392Z","caller":"traceutil/trace.go:171","msg":"trace[463350233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"467.223786ms","start":"2026-07-10T14:45:54.041159Z","end":"2026-07-10T14:45:54.508383Z","steps":["trace[463350233] 'agreement among raft nodes before linearized reading' (duration: 467.157932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:54.041144Z","time spent":"467.268388ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-10T14:45:54.50758Z","caller":"traceutil/trace.go:171","msg":"trace[933962560] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"790.046297ms","start":"2026-07-10T14:45:53.717522Z","end":"2026-07-10T14:45:54.507568Z","steps":["trace[933962560] 'process raft request' (duration: 788.89113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.717491Z","time spent":"790.98954ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-10T14:45:54.508659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.979353ms","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-10T14:45:54.508695Z","caller":"traceutil/trace.go:171","msg":"trace[1094577171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"256.057998ms","start":"2026-07-10T14:45:54.252628Z","end":"2026-07-10T14:45:54.508686Z","steps":["trace[1094577171] 'agreement among raft nodes before linearized reading' (duration: 255.940391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.508833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.352132ms","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-10T14:45:54.508953Z","caller":"traceutil/trace.go:171","msg":"trace[1964200404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"262.511931ms","start":"2026-07-10T14:45:54.246402Z","end":"2026-07-10T14:45:54.508914Z","steps":["trace[1964200404] 'agreement among raft nodes before linearized reading' (duration: 262.206934ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:45:54.508889Z","caller":"traceutil/trace.go:171","msg":"trace[1296880745] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"717.240607ms","start":"2026-07-10T14:45:53.790332Z","end":"2026-07-10T14:45:54.507573Z","steps":["trace[1296880745] 'process raft request' (duration: 716.78803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:45:54.509374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:53.790296Z","time spent":"718.921915ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T14:46:00.082081Z","caller":"traceutil/trace.go:171","msg":"trace[570809796] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"482.215498ms","start":"2026-07-10T14:45:59.599826Z","end":"2026-07-10T14:46:00.082041Z","steps":["trace[570809796] 'process raft request' (duration: 481.227469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:00.082369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:45:59.599777Z","time spent":"482.423271ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:46:01.240121Z","caller":"traceutil/trace.go:171","msg":"trace[3870976] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"204.26948ms","start":"2026-07-10T14:46:01.035825Z","end":"2026-07-10T14:46:01.240094Z","steps":["trace[3870976] 'process raft request' (duration: 203.584509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:01.501112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.164167ms","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-10T14:46:01.501246Z","caller":"traceutil/trace.go:171","msg":"trace[457374469] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:640; }","duration":"194.321596ms","start":"2026-07-10T14:46:01.306902Z","end":"2026-07-10T14:46:01.501224Z","steps":["trace[457374469] 'range keys from in-memory index tree' (duration: 194.130144ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:01.501583Z","caller":"traceutil/trace.go:171","msg":"trace[1238961853] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"201.542495ms","start":"2026-07-10T14:46:01.299973Z","end":"2026-07-10T14:46:01.501516Z","steps":["trace[1238961853] 'process raft request' (duration: 186.589907ms)","trace[1238961853] 'compare' (duration: 14.482459ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:46:02.77981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891365821170838113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T14:46:02.990694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235257041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:46:02.990782Z","caller":"traceutil/trace.go:171","msg":"trace[1775837126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.235390739s","start":"2026-07-10T14:46:01.755366Z","end":"2026-07-10T14:46:02.990757Z","steps":["trace[1775837126] 'range keys from in-memory index tree' (duration: 1.235184697s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:02.99082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:01.755328Z","time spent":"1.235482695s","remote":"127.0.0.1:51356","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-10T14:46:03.457315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.901478ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-10T14:46:03.462392Z","caller":"traceutil/trace.go:171","msg":"trace[2086825513] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"1.183639397s","start":"2026-07-10T14:46:02.278694Z","end":"2026-07-10T14:46:03.462333Z","steps":["trace[2086825513] 'read index received' (duration: 531.665007ms)","trace[2086825513] 'applied index is now lower than readState.Index' (duration: 651.9732ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:46:03.46248Z","caller":"traceutil/trace.go:171","msg":"trace[1553691112] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.561649073s","start":"2026-07-10T14:46:01.900792Z","end":"2026-07-10T14:46:03.462441Z","steps":["trace[1553691112] 'process raft request' (duration: 1.556796955s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.462659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:01.900755Z","time spent":"1.561816393s","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T14:46:03.462711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183992688s","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-10T14:46:03.462876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.658994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-10T14:46:03.46295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.482362ms","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-10T14:46:03.462998Z","caller":"traceutil/trace.go:171","msg":"trace[974948243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"852.575138ms","start":"2026-07-10T14:46:02.610409Z","end":"2026-07-10T14:46:03.462984Z","steps":["trace[974948243] 'agreement among raft nodes before linearized reading' (duration: 852.437049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.463025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:02.610384Z","time spent":"852.635871ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-10T14:46:03.462817Z","caller":"traceutil/trace.go:171","msg":"trace[1047989319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"1.184165229s","start":"2026-07-10T14:46:02.278626Z","end":"2026-07-10T14:46:03.462792Z","steps":["trace[1047989319] 'agreement among raft nodes before linearized reading' (duration: 1.183878751s)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:03.462974Z","caller":"traceutil/trace.go:171","msg":"trace[469333791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"881.816434ms","start":"2026-07-10T14:46:02.581139Z","end":"2026-07-10T14:46:03.462955Z","steps":["trace[469333791] 'agreement among raft nodes before linearized reading' (duration: 881.477523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.463092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:02.278591Z","time spent":"1.184478368s","remote":"127.0.0.1:51674","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":"warn","ts":"2026-07-10T14:46:03.463184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:02.58111Z","time spent":"882.018725ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-10T14:46:03.463318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.184353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:46:03.464717Z","caller":"traceutil/trace.go:171","msg":"trace[920009664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"471.477671ms","start":"2026-07-10T14:46:02.991952Z","end":"2026-07-10T14:46:03.46343Z","steps":["trace[920009664] 'agreement among raft nodes before linearized reading' (duration: 470.919757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.46482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:02.991924Z","time spent":"472.865136ms","remote":"127.0.0.1:51358","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-10T14:46:03.466388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.046683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-10T14:46:03.466862Z","caller":"traceutil/trace.go:171","msg":"trace[10389350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"885.219123ms","start":"2026-07-10T14:46:02.581261Z","end":"2026-07-10T14:46:03.46648Z","steps":["trace[10389350] 'agreement among raft nodes before linearized reading' (duration: 881.591379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.467299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:02.581219Z","time spent":"885.750066ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-10T14:46:03.602533Z","caller":"traceutil/trace.go:171","msg":"trace[1695856798] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"133.249455ms","start":"2026-07-10T14:46:03.469246Z","end":"2026-07-10T14:46:03.602495Z","steps":["trace[1695856798] 'process raft request' (duration: 113.672331ms)","trace[1695856798] 'compare' (duration: 19.308617ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:46:03.602852Z","caller":"traceutil/trace.go:171","msg":"trace[1659973458] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"128.057675ms","start":"2026-07-10T14:46:03.474765Z","end":"2026-07-10T14:46:03.602823Z","steps":["trace[1659973458] 'process raft request' (duration: 127.667741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:03.603407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.714938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:46:03.603663Z","caller":"traceutil/trace.go:171","msg":"trace[1932963073] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"129.4465ms","start":"2026-07-10T14:46:03.473665Z","end":"2026-07-10T14:46:03.603112Z","steps":["trace[1932963073] 'read index received' (duration: 72.074563ms)","trace[1932963073] 'applied index is now lower than readState.Index' (duration: 57.370907ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:46:03.603754Z","caller":"traceutil/trace.go:171","msg":"trace[1939911802] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"128.375624ms","start":"2026-07-10T14:46:03.47485Z","end":"2026-07-10T14:46:03.603225Z","steps":["trace[1939911802] 'process raft request' (duration: 127.832071ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:03.603831Z","caller":"traceutil/trace.go:171","msg":"trace[1155987947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"129.815603ms","start":"2026-07-10T14:46:03.473631Z","end":"2026-07-10T14:46:03.603446Z","steps":["trace[1155987947] 'agreement among raft nodes before linearized reading' (duration: 129.697146ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:05.248687Z","caller":"traceutil/trace.go:171","msg":"trace[803481679] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"380.837722ms","start":"2026-07-10T14:46:04.867826Z","end":"2026-07-10T14:46:05.248664Z","steps":["trace[803481679] 'process raft request' (duration: 377.578259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:05.248822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:04.867798Z","time spent":"380.96165ms","remote":"127.0.0.1:51674","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-10T14:46:16.105851Z","caller":"traceutil/trace.go:171","msg":"trace[786938918] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"192.079317ms","start":"2026-07-10T14:46:15.913732Z","end":"2026-07-10T14:46:16.105812Z","steps":["trace[786938918] 'process raft request' (duration: 191.912486ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:19.268392Z","caller":"traceutil/trace.go:171","msg":"trace[1662630507] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"415.175355ms","start":"2026-07-10T14:46:18.853181Z","end":"2026-07-10T14:46:19.268356Z","steps":["trace[1662630507] 'process raft request' (duration: 414.965341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:46:19.268616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:46:18.853162Z","time spent":"415.352976ms","remote":"127.0.0.1:51632","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-10T14:46:28.169812Z","caller":"traceutil/trace.go:171","msg":"trace[2026693275] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"129.173889ms","start":"2026-07-10T14:46:28.040605Z","end":"2026-07-10T14:46:28.169779Z","steps":["trace[2026693275] 'process raft request' (duration: 106.729747ms)","trace[2026693275] 'compare' (duration: 22.235477ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:46:28.170016Z","caller":"traceutil/trace.go:171","msg":"trace[18842968] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"122.407019ms","start":"2026-07-10T14:46:28.047575Z","end":"2026-07-10T14:46:28.169982Z","steps":["trace[18842968] 'process raft request' (duration: 122.138599ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:46.647844Z","caller":"traceutil/trace.go:171","msg":"trace[1040420113] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"135.145334ms","start":"2026-07-10T14:46:46.512652Z","end":"2026-07-10T14:46:46.647797Z","steps":["trace[1040420113] 'process raft request' (duration: 106.966807ms)","trace[1040420113] 'compare' (duration: 27.912108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:46:46.909281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:46:46.909388Z","caller":"traceutil/trace.go:171","msg":"trace[590186020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"153.426602ms","start":"2026-07-10T14:46:46.755937Z","end":"2026-07-10T14:46:46.909364Z","steps":["trace[590186020] 'range keys from in-memory index tree' (duration: 153.120409ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:48.148773Z","caller":"traceutil/trace.go:171","msg":"trace[1838058418] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"287.602969ms","start":"2026-07-10T14:46:47.861118Z","end":"2026-07-10T14:46:48.148721Z","steps":["trace[1838058418] 'process raft request' (duration: 257.997905ms)","trace[1838058418] 'compare' (duration: 29.246398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:46:48.854824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.081492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:46:48.854979Z","caller":"traceutil/trace.go:171","msg":"trace[2126675162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"101.331541ms","start":"2026-07-10T14:46:48.753612Z","end":"2026-07-10T14:46:48.854943Z","steps":["trace[2126675162] 'range keys from in-memory index tree' (duration: 100.982025ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:46:50.027491Z","caller":"traceutil/trace.go:171","msg":"trace[486466914] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"127.307174ms","start":"2026-07-10T14:46:49.900143Z","end":"2026-07-10T14:46:50.02745Z","steps":["trace[486466914] 'process raft request' (duration: 84.388541ms)","trace[486466914] 'compare' (duration: 42.792004ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:47:02.133365Z","caller":"traceutil/trace.go:171","msg":"trace[2041884984] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"153.144629ms","start":"2026-07-10T14:47:01.980195Z","end":"2026-07-10T14:47:02.13334Z","steps":["trace[2041884984] 'read index received' (duration: 152.876628ms)","trace[2041884984] 'applied index is now lower than readState.Index' (duration: 266.431µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:47:02.133687Z","caller":"traceutil/trace.go:171","msg":"trace[1706496787] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"190.957557ms","start":"2026-07-10T14:47:01.94271Z","end":"2026-07-10T14:47:02.133668Z","steps":["trace[1706496787] 'process raft request' (duration: 190.425016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:47:02.134065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.855803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-10T14:47:02.13412Z","caller":"traceutil/trace.go:171","msg":"trace[364738651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"153.945199ms","start":"2026-07-10T14:47:01.980158Z","end":"2026-07-10T14:47:02.134103Z","steps":["trace[364738651] 'agreement among raft nodes before linearized reading' (duration: 153.783767ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:47:27.165656Z","caller":"traceutil/trace.go:171","msg":"trace[1288540821] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"228.861881ms","start":"2026-07-10T14:47:26.936764Z","end":"2026-07-10T14:47:27.165626Z","steps":["trace[1288540821] 'process raft request' (duration: 206.808532ms)","trace[1288540821] 'compare' (duration: 21.9316ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:47:28.399006Z","caller":"traceutil/trace.go:171","msg":"trace[1509754311] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"103.231252ms","start":"2026-07-10T14:47:28.29575Z","end":"2026-07-10T14:47:28.398982Z","steps":["trace[1509754311] 'process raft request' (duration: 103.116673ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:47:28.693064Z","caller":"traceutil/trace.go:171","msg":"trace[817397455] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"342.443089ms","start":"2026-07-10T14:47:28.350561Z","end":"2026-07-10T14:47:28.693004Z","steps":["trace[817397455] 'read index received' (duration: 48.47323ms)","trace[817397455] 'applied index is now lower than readState.Index' (duration: 293.968809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:47:28.693344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.764334ms","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-10T14:47:28.693419Z","caller":"traceutil/trace.go:171","msg":"trace[2022916564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"342.886684ms","start":"2026-07-10T14:47:28.350515Z","end":"2026-07-10T14:47:28.693402Z","steps":["trace[2022916564] 'agreement among raft nodes before linearized reading' (duration: 342.650386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:47:28.693462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:47:28.350497Z","time spent":"342.956239ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-10T14:47:28.693586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.14571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-10T14:47:28.693702Z","caller":"traceutil/trace.go:171","msg":"trace[1344060341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"137.397919ms","start":"2026-07-10T14:47:28.556289Z","end":"2026-07-10T14:47:28.693687Z","steps":["trace[1344060341] 'agreement among raft nodes before linearized reading' (duration: 136.978136ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:47:28.693786Z","caller":"traceutil/trace.go:171","msg":"trace[762315328] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"395.922708ms","start":"2026-07-10T14:47:28.297842Z","end":"2026-07-10T14:47:28.693765Z","steps":["trace[762315328] 'process raft request' (duration: 311.278411ms)","trace[762315328] 'compare' (duration: 83.757077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:47:28.693967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:47:28.297834Z","time spent":"396.07012ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:47:28.836082Z","caller":"traceutil/trace.go:171","msg":"trace[1982748621] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"137.91209ms","start":"2026-07-10T14:47:28.698143Z","end":"2026-07-10T14:47:28.836055Z","steps":["trace[1982748621] 'process raft request' (duration: 125.534633ms)","trace[1982748621] 'compare' (duration: 12.067313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:47:42.292129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.957707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-10T14:47:42.292218Z","caller":"traceutil/trace.go:171","msg":"trace[1295801509] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1213; }","duration":"117.10417ms","start":"2026-07-10T14:47:42.175101Z","end":"2026-07-10T14:47:42.292206Z","steps":["trace[1295801509] 'count revisions from in-memory index tree' (duration: 116.787234ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:47:42.95757Z","caller":"traceutil/trace.go:171","msg":"trace[1374996319] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"146.91903ms","start":"2026-07-10T14:47:42.810623Z","end":"2026-07-10T14:47:42.957542Z","steps":["trace[1374996319] 'read index received' (duration: 146.686141ms)","trace[1374996319] 'applied index is now lower than readState.Index' (duration: 231.679µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:47:42.957747Z","caller":"traceutil/trace.go:171","msg":"trace[320231119] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"193.242658ms","start":"2026-07-10T14:47:42.764492Z","end":"2026-07-10T14:47:42.957735Z","steps":["trace[320231119] 'process raft request' (duration: 192.882758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:47:42.957957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.326943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-10T14:47:42.958016Z","caller":"traceutil/trace.go:171","msg":"trace[779167301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"147.429451ms","start":"2026-07-10T14:47:42.810572Z","end":"2026-07-10T14:47:42.958002Z","steps":["trace[779167301] 'agreement among raft nodes before linearized reading' (duration: 147.252267ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:47:43.110896Z","caller":"traceutil/trace.go:171","msg":"trace[861591266] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"147.608744ms","start":"2026-07-10T14:47:42.963267Z","end":"2026-07-10T14:47:43.110876Z","steps":["trace[861591266] 'process raft request' (duration: 84.649621ms)","trace[861591266] 'compare' (duration: 62.750235ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:47:53.192799Z","caller":"traceutil/trace.go:171","msg":"trace[1781957108] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"174.180585ms","start":"2026-07-10T14:47:53.018577Z","end":"2026-07-10T14:47:53.192757Z","steps":["trace[1781957108] 'process raft request' (duration: 93.050196ms)","trace[1781957108] 'compare' (duration: 80.990918ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:12.463731Z","caller":"traceutil/trace.go:171","msg":"trace[1433917010] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"128.062031ms","start":"2026-07-10T14:48:12.335644Z","end":"2026-07-10T14:48:12.463706Z","steps":["trace[1433917010] 'read index received' (duration: 127.785879ms)","trace[1433917010] 'applied index is now lower than readState.Index' (duration: 275.072µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:12.463785Z","caller":"traceutil/trace.go:171","msg":"trace[329993030] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"161.454276ms","start":"2026-07-10T14:48:12.302297Z","end":"2026-07-10T14:48:12.463751Z","steps":["trace[329993030] 'process raft request' (duration: 161.25302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:12.464138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.470405ms","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-10T14:48:12.464184Z","caller":"traceutil/trace.go:171","msg":"trace[1609217081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1343; }","duration":"128.617757ms","start":"2026-07-10T14:48:12.335555Z","end":"2026-07-10T14:48:12.464173Z","steps":["trace[1609217081] 'agreement among raft nodes before linearized reading' (duration: 128.282769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:12.645039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.240578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T14:48:12.645144Z","caller":"traceutil/trace.go:171","msg":"trace[393429946] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"176.928552ms","start":"2026-07-10T14:48:12.468195Z","end":"2026-07-10T14:48:12.645124Z","steps":["trace[393429946] 'process raft request' (duration: 54.465495ms)","trace[393429946] 'compare' (duration: 122.125489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:12.880024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.771533ms","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-10T14:48:12.880119Z","caller":"traceutil/trace.go:171","msg":"trace[1488400302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"119.923025ms","start":"2026-07-10T14:48:12.760175Z","end":"2026-07-10T14:48:12.880099Z","steps":["trace[1488400302] 'range keys from in-memory index tree' (duration: 119.536094ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:13.058256Z","caller":"traceutil/trace.go:171","msg":"trace[208475696] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"173.491679ms","start":"2026-07-10T14:48:12.884736Z","end":"2026-07-10T14:48:13.058228Z","steps":["trace[208475696] 'process raft request' (duration: 173.317004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:13.381936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.757365ms","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-10T14:48:13.38201Z","caller":"traceutil/trace.go:171","msg":"trace[1193196893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"188.880185ms","start":"2026-07-10T14:48:13.193113Z","end":"2026-07-10T14:48:13.381993Z","steps":["trace[1193196893] 'range keys from in-memory index tree' (duration: 188.602713ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:13.493213Z","caller":"traceutil/trace.go:171","msg":"trace[908350338] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"106.707362ms","start":"2026-07-10T14:48:13.38648Z","end":"2026-07-10T14:48:13.493188Z","steps":["trace[908350338] 'process raft request' (duration: 106.56542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:14.036013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.285837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T14:48:14.036204Z","caller":"traceutil/trace.go:171","msg":"trace[1976426786] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"648.411684ms","start":"2026-07-10T14:48:13.387775Z","end":"2026-07-10T14:48:14.036186Z","steps":["trace[1976426786] 'process raft request' (duration: 648.329767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:14.036267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:13.387751Z","time spent":"648.48659ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:14.036479Z","caller":"traceutil/trace.go:171","msg":"trace[1789739018] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"649.743085ms","start":"2026-07-10T14:48:13.386722Z","end":"2026-07-10T14:48:14.036465Z","steps":["trace[1789739018] 'process raft request' (duration: 231.900881ms)","trace[1789739018] 'compare' (duration: 417.183389ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:14.036534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:13.386714Z","time spent":"649.79295ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:14.036513Z","caller":"traceutil/trace.go:171","msg":"trace[1101794101] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1406; }","duration":"282.112538ms","start":"2026-07-10T14:48:13.754351Z","end":"2026-07-10T14:48:14.036464Z","steps":["trace[1101794101] 'read index received' (duration: 50.044µs)","trace[1101794101] 'applied index is now lower than readState.Index' (duration: 282.061254ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:14.036663Z","caller":"traceutil/trace.go:171","msg":"trace[715735486] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"624.096062ms","start":"2026-07-10T14:48:13.412538Z","end":"2026-07-10T14:48:14.036634Z","steps":["trace[715735486] 'process raft request' (duration: 623.614292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:14.036832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:13.412518Z","time spent":"624.225643ms","remote":"127.0.0.1:51674","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-10T14:48:14.037193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.275591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:14.037276Z","caller":"traceutil/trace.go:171","msg":"trace[395896499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"282.959749ms","start":"2026-07-10T14:48:13.754299Z","end":"2026-07-10T14:48:14.037259Z","steps":["trace[395896499] 'agreement among raft nodes before linearized reading' (duration: 282.274561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.14935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.153639ms","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-10T14:48:15.149442Z","caller":"traceutil/trace.go:171","msg":"trace[1479622543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"500.323274ms","start":"2026-07-10T14:48:14.6491Z","end":"2026-07-10T14:48:15.149423Z","steps":["trace[1479622543] 'range keys from in-memory index tree' (duration: 499.91463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.149488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:14.64908Z","time spent":"500.39785ms","remote":"127.0.0.1:51674","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-10T14:48:15.14999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.985339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:15.150072Z","caller":"traceutil/trace.go:171","msg":"trace[189071001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"395.087898ms","start":"2026-07-10T14:48:14.754964Z","end":"2026-07-10T14:48:15.150051Z","steps":["trace[189071001] 'range keys from in-memory index tree' (duration: 394.840388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.15011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:14.754956Z","time spent":"395.146763ms","remote":"127.0.0.1:51356","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-10T14:48:15.150597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.731848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-10T14:48:15.150633Z","caller":"traceutil/trace.go:171","msg":"trace[1150285896] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1349; }","duration":"344.809975ms","start":"2026-07-10T14:48:14.805811Z","end":"2026-07-10T14:48:15.150621Z","steps":["trace[1150285896] 'range keys from in-memory index tree' (duration: 344.667063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.150663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:14.805791Z","time spent":"344.86356ms","remote":"127.0.0.1:51448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-10T14:48:15.150688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.86628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:71499"} {"level":"info","ts":"2026-07-10T14:48:15.15075Z","caller":"traceutil/trace.go:171","msg":"trace[1216624888] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1349; }","duration":"344.974568ms","start":"2026-07-10T14:48:14.805759Z","end":"2026-07-10T14:48:15.150734Z","steps":["trace[1216624888] 'range keys from in-memory index tree' (duration: 344.683864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.150797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:14.805736Z","time spent":"345.052065ms","remote":"127.0.0.1:51444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":71522,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-10T14:48:15.571338Z","caller":"traceutil/trace.go:171","msg":"trace[999574142] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"406.916594ms","start":"2026-07-10T14:48:15.164391Z","end":"2026-07-10T14:48:15.571308Z","steps":["trace[999574142] 'read index received' (duration: 311.665225ms)","trace[999574142] 'applied index is now lower than readState.Index' (duration: 95.249779ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:15.5716Z","caller":"traceutil/trace.go:171","msg":"trace[897141236] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"416.613461ms","start":"2026-07-10T14:48:15.154958Z","end":"2026-07-10T14:48:15.571571Z","steps":["trace[897141236] 'process raft request' (duration: 321.057797ms)","trace[897141236] 'compare' (duration: 94.957235ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:15.571608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.743561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-10T14:48:15.571653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.226863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:71499"} {"level":"info","ts":"2026-07-10T14:48:15.571694Z","caller":"traceutil/trace.go:171","msg":"trace[697384535] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1351; }","duration":"405.302459ms","start":"2026-07-10T14:48:15.16638Z","end":"2026-07-10T14:48:15.571683Z","steps":["trace[697384535] 'agreement among raft nodes before linearized reading' (duration: 405.103223ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:15.571704Z","caller":"traceutil/trace.go:171","msg":"trace[365952996] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1351; }","duration":"416.872352ms","start":"2026-07-10T14:48:15.154805Z","end":"2026-07-10T14:48:15.571678Z","steps":["trace[365952996] 'agreement among raft nodes before linearized reading' (duration: 416.683256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:15.571735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:15.166366Z","time spent":"405.361264ms","remote":"127.0.0.1:51444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":71522,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-10T14:48:15.571752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:15.154793Z","time spent":"416.949348ms","remote":"127.0.0.1:51448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-10T14:48:15.571775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:15.15493Z","time spent":"416.746272ms","remote":"127.0.0.1:51674","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-10T14:48:16.293533Z","caller":"traceutil/trace.go:171","msg":"trace[1316336844] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"250.654307ms","start":"2026-07-10T14:48:16.042852Z","end":"2026-07-10T14:48:16.293506Z","steps":["trace[1316336844] 'process raft request' (duration: 250.360983ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:16.635606Z","caller":"traceutil/trace.go:171","msg":"trace[1637561526] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"590.441299ms","start":"2026-07-10T14:48:16.045142Z","end":"2026-07-10T14:48:16.635584Z","steps":["trace[1637561526] 'process raft request' (duration: 506.48802ms)","trace[1637561526] 'compare' (duration: 83.80915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:16.63581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.045126Z","time spent":"590.549888ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:16.635808Z","caller":"traceutil/trace.go:171","msg":"trace[1427404288] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1412; }","duration":"450.60646ms","start":"2026-07-10T14:48:16.185151Z","end":"2026-07-10T14:48:16.635757Z","steps":["trace[1427404288] 'read index received' (duration: 108.055901ms)","trace[1427404288] 'applied index is now lower than readState.Index' (duration: 342.548639ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:16.636128Z","caller":"traceutil/trace.go:171","msg":"trace[412724045] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"590.757828ms","start":"2026-07-10T14:48:16.045355Z","end":"2026-07-10T14:48:16.636113Z","steps":["trace[412724045] 'process raft request' (duration: 590.19866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:16.636156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.989626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"warn","ts":"2026-07-10T14:48:16.636215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.04534Z","time spent":"590.833223ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:16.636225Z","caller":"traceutil/trace.go:171","msg":"trace[1811607278] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1355; }","duration":"451.067071ms","start":"2026-07-10T14:48:16.18514Z","end":"2026-07-10T14:48:16.636208Z","steps":["trace[1811607278] 'agreement among raft nodes before linearized reading' (duration: 450.799453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:16.636248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.450764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-10T14:48:16.636668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.185068Z","time spent":"451.549465ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2499,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-07-10T14:48:16.63675Z","caller":"traceutil/trace.go:171","msg":"trace[902138361] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1355; }","duration":"409.97921ms","start":"2026-07-10T14:48:16.226739Z","end":"2026-07-10T14:48:16.636718Z","steps":["trace[902138361] 'agreement among raft nodes before linearized reading' (duration: 409.446154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:16.636878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.226723Z","time spent":"410.120871ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-10T14:48:16.636383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.764333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-10T14:48:16.636955Z","caller":"traceutil/trace.go:171","msg":"trace[1728581743] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:1355; }","duration":"154.361654ms","start":"2026-07-10T14:48:16.482578Z","end":"2026-07-10T14:48:16.63694Z","steps":["trace[1728581743] 'agreement among raft nodes before linearized reading' (duration: 153.704189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:16.636399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.031033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:16.637202Z","caller":"traceutil/trace.go:171","msg":"trace[1718886505] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1355; }","duration":"326.879662ms","start":"2026-07-10T14:48:16.310308Z","end":"2026-07-10T14:48:16.637188Z","steps":["trace[1718886505] 'agreement among raft nodes before linearized reading' (duration: 326.027254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:16.63725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.310291Z","time spent":"326.937566ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-10T14:48:17.272745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.246132ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-10T14:48:17.272876Z","caller":"traceutil/trace.go:171","msg":"trace[16162917] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"518.27342ms","start":"2026-07-10T14:48:16.754582Z","end":"2026-07-10T14:48:17.272855Z","steps":["trace[16162917] 'read index received' (duration: 343.897926ms)","trace[16162917] 'applied index is now lower than readState.Index' (duration: 174.373594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:17.273015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.638134Z","time spent":"634.877256ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-10T14:48:17.273224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.002011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:17.273515Z","caller":"traceutil/trace.go:171","msg":"trace[1943653623] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1355; }","duration":"218.310493ms","start":"2026-07-10T14:48:17.055186Z","end":"2026-07-10T14:48:17.273496Z","steps":["trace[1943653623] 'agreement among raft nodes before linearized reading' (duration: 217.956783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:17.273496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.899607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:17.273599Z","caller":"traceutil/trace.go:171","msg":"trace[524132575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"519.059414ms","start":"2026-07-10T14:48:16.754515Z","end":"2026-07-10T14:48:17.273575Z","steps":["trace[524132575] 'agreement among raft nodes before linearized reading' (duration: 518.894523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:17.273652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:16.754492Z","time spent":"519.149942ms","remote":"127.0.0.1:51358","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-10T14:48:17.273782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.747127ms","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-10T14:48:17.27387Z","caller":"traceutil/trace.go:171","msg":"trace[1490881298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"105.892988ms","start":"2026-07-10T14:48:17.167961Z","end":"2026-07-10T14:48:17.273854Z","steps":["trace[1490881298] 'agreement among raft nodes before linearized reading' (duration: 105.451823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:17.851074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.891794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T14:48:17.851231Z","caller":"traceutil/trace.go:171","msg":"trace[984955491] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"576.201644ms","start":"2026-07-10T14:48:17.275004Z","end":"2026-07-10T14:48:17.851205Z","steps":["trace[984955491] 'process raft request' (duration: 206.054655ms)","trace[984955491] 'compare' (duration: 369.394711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:17.851303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.274974Z","time spent":"576.296318ms","remote":"127.0.0.1:51408","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-10T14:48:18.076417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891365821170839807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T14:48:18.57758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891365821170839807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T14:48:18.595041Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.113948049s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-10T14:48:18.779397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.99722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T14:48:18.779564Z","caller":"traceutil/trace.go:171","msg":"trace[331619020] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1417; }","duration":"1.203221681s","start":"2026-07-10T14:48:17.576328Z","end":"2026-07-10T14:48:18.77955Z","steps":["trace[331619020] 'read index received' (duration: 1.018981224s)","trace[331619020] 'applied index is now lower than readState.Index' (duration: 184.239257ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:18.779677Z","caller":"traceutil/trace.go:171","msg":"trace[794837410] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.50063662s","start":"2026-07-10T14:48:17.278982Z","end":"2026-07-10T14:48:18.779619Z","steps":["trace[794837410] 'process raft request' (duration: 1.316320168s)","trace[794837410] 'compare' (duration: 182.522731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:18.779718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.278955Z","time spent":"1.500740526s","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:18.77992Z","caller":"traceutil/trace.go:171","msg":"trace[1043574261] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.346907501s","start":"2026-07-10T14:48:17.433005Z","end":"2026-07-10T14:48:18.779913Z","steps":["trace[1043574261] 'process raft request' (duration: 1.346471904s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.779956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.43295Z","time spent":"1.346985507s","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:18.780049Z","caller":"traceutil/trace.go:171","msg":"trace[1155472355] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"480.92273ms","start":"2026-07-10T14:48:18.299118Z","end":"2026-07-10T14:48:18.780041Z","steps":["trace[1155472355] 'process raft request' (duration: 480.405597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.780082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:18.299085Z","time spent":"480.979597ms","remote":"127.0.0.1:51674","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-10T14:48:18.780229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203910314s","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-10T14:48:18.780254Z","caller":"traceutil/trace.go:171","msg":"trace[899350546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1359; }","duration":"1.203980481s","start":"2026-07-10T14:48:17.576267Z","end":"2026-07-10T14:48:18.780248Z","steps":["trace[899350546] 'agreement among raft nodes before linearized reading' (duration: 1.203886486s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.780278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.576245Z","time spent":"1.204027518s","remote":"127.0.0.1:51674","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-10T14:48:18.780465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128848833s","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-10T14:48:18.780498Z","caller":"traceutil/trace.go:171","msg":"trace[1965623568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"1.128934338s","start":"2026-07-10T14:48:17.651556Z","end":"2026-07-10T14:48:18.78049Z","steps":["trace[1965623568] 'agreement among raft nodes before linearized reading' (duration: 1.128801826s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.780518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.651532Z","time spent":"1.128979876s","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-10T14:48:18.780661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.529852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-10T14:48:18.780686Z","caller":"traceutil/trace.go:171","msg":"trace[871735099] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1359; }","duration":"926.605478ms","start":"2026-07-10T14:48:17.854075Z","end":"2026-07-10T14:48:18.78068Z","steps":["trace[871735099] 'agreement among raft nodes before linearized reading' (duration: 926.554921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.780705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.854047Z","time spent":"926.653966ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-10T14:48:18.780703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.286581ms","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-10T14:48:18.780759Z","caller":"traceutil/trace.go:171","msg":"trace[361181380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1359; }","duration":"139.398634ms","start":"2026-07-10T14:48:18.641348Z","end":"2026-07-10T14:48:18.780746Z","steps":["trace[361181380] 'agreement among raft nodes before linearized reading' (duration: 139.233463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.780955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.524947ms","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-10T14:48:18.781001Z","caller":"traceutil/trace.go:171","msg":"trace[203542804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"139.621153ms","start":"2026-07-10T14:48:18.641373Z","end":"2026-07-10T14:48:18.780994Z","steps":["trace[203542804] 'agreement among raft nodes before linearized reading' (duration: 139.539747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.781403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024749187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:18.781465Z","caller":"traceutil/trace.go:171","msg":"trace[1022276263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1359; }","duration":"1.025151275s","start":"2026-07-10T14:48:17.756275Z","end":"2026-07-10T14:48:18.781426Z","steps":["trace[1022276263] 'agreement among raft nodes before linearized reading' (duration: 1.024523289s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:18.781488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:17.756249Z","time spent":"1.02523336s","remote":"127.0.0.1:51356","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-10T14:48:19.334983Z","caller":"traceutil/trace.go:171","msg":"trace[619164018] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"548.647912ms","start":"2026-07-10T14:48:18.786303Z","end":"2026-07-10T14:48:19.334951Z","steps":["trace[619164018] 'process raft request' (duration: 548.414814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:19.335188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:18.786296Z","time spent":"548.768765ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T14:48:19.335361Z","caller":"traceutil/trace.go:171","msg":"trace[190663758] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"547.845656ms","start":"2026-07-10T14:48:18.787496Z","end":"2026-07-10T14:48:19.335341Z","steps":["trace[190663758] 'process raft request' (duration: 547.674585ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:19.335364Z","caller":"traceutil/trace.go:171","msg":"trace[1355395097] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"548.841191ms","start":"2026-07-10T14:48:18.78648Z","end":"2026-07-10T14:48:19.335321Z","steps":["trace[1355395097] 'process raft request' (duration: 548.416884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:19.335479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:18.787478Z","time spent":"547.929981ms","remote":"127.0.0.1:51674","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-10T14:48:19.335535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:18.786432Z","time spent":"549.012742ms","remote":"127.0.0.1:51674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T14:48:19.335558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.174378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T14:48:19.335659Z","caller":"traceutil/trace.go:171","msg":"trace[2011611084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"546.328219ms","start":"2026-07-10T14:48:18.789306Z","end":"2026-07-10T14:48:19.335634Z","steps":["trace[2011611084] 'agreement among raft nodes before linearized reading' (duration: 546.1278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:19.335706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:18.789294Z","time spent":"546.403825ms","remote":"127.0.0.1:51356","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-10T14:48:19.335281Z","caller":"traceutil/trace.go:171","msg":"trace[1659028178] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1420; }","duration":"545.900002ms","start":"2026-07-10T14:48:18.789356Z","end":"2026-07-10T14:48:19.335256Z","steps":["trace[1659028178] 'read index received' (duration: 64.330799ms)","trace[1659028178] 'applied index is now lower than readState.Index' (duration: 481.567713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:19.336269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.439335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-10T14:48:19.336314Z","caller":"traceutil/trace.go:171","msg":"trace[658086412] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1363; }","duration":"302.53684ms","start":"2026-07-10T14:48:19.033758Z","end":"2026-07-10T14:48:19.336295Z","steps":["trace[658086412] 'agreement among raft nodes before linearized reading' (duration: 302.453874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:19.336346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T14:48:19.03373Z","time spent":"302.609065ms","remote":"127.0.0.1:51790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-10T14:48:43.669233Z","caller":"traceutil/trace.go:171","msg":"trace[930959636] linearizableReadLoop","detail":"{readStateIndex:1518; appliedIndex:1517; }","duration":"204.17389ms","start":"2026-07-10T14:48:43.465034Z","end":"2026-07-10T14:48:43.669208Z","steps":["trace[930959636] 'read index received' (duration: 204.022995ms)","trace[930959636] 'applied index is now lower than readState.Index' (duration: 149.895µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:43.669343Z","caller":"traceutil/trace.go:171","msg":"trace[560829791] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"211.722917ms","start":"2026-07-10T14:48:43.457591Z","end":"2026-07-10T14:48:43.669314Z","steps":["trace[560829791] 'process raft request' (duration: 211.448806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.66948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.422622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-10T14:48:43.669527Z","caller":"traceutil/trace.go:171","msg":"trace[12282970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1451; }","duration":"204.513039ms","start":"2026-07-10T14:48:43.465002Z","end":"2026-07-10T14:48:43.669515Z","steps":["trace[12282970] 'agreement among raft nodes before linearized reading' (duration: 204.357704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.66963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.48383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-10T14:48:43.669711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.111202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-07-10T14:48:43.669755Z","caller":"traceutil/trace.go:171","msg":"trace[398752726] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1451; }","duration":"151.18309ms","start":"2026-07-10T14:48:43.518559Z","end":"2026-07-10T14:48:43.669742Z","steps":["trace[398752726] 'agreement among raft nodes before linearized reading' (duration: 151.089543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.669796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.98603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-07-10T14:48:43.669834Z","caller":"traceutil/trace.go:171","msg":"trace[1383291762] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1451; }","duration":"154.057308ms","start":"2026-07-10T14:48:43.515765Z","end":"2026-07-10T14:48:43.669822Z","steps":["trace[1383291762] 'agreement among raft nodes before linearized reading' (duration: 153.96999ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:43.669709Z","caller":"traceutil/trace.go:171","msg":"trace[1608406821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1451; }","duration":"204.609416ms","start":"2026-07-10T14:48:43.465081Z","end":"2026-07-10T14:48:43.66969Z","steps":["trace[1608406821] 'agreement among raft nodes before linearized reading' (duration: 204.427462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.670009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.520164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-10T14:48:43.67006Z","caller":"traceutil/trace.go:171","msg":"trace[1072643140] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:1451; }","duration":"140.582642ms","start":"2026-07-10T14:48:43.529461Z","end":"2026-07-10T14:48:43.670044Z","steps":["trace[1072643140] 'agreement among raft nodes before linearized reading' (duration: 140.378549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.67035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.902187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-07-10T14:48:43.670422Z","caller":"traceutil/trace.go:171","msg":"trace[132116831] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1451; }","duration":"174.023643ms","start":"2026-07-10T14:48:43.496381Z","end":"2026-07-10T14:48:43.670404Z","steps":["trace[132116831] 'agreement among raft nodes before linearized reading' (duration: 173.594887ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:43.913613Z","caller":"traceutil/trace.go:171","msg":"trace[1807153987] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"158.417867ms","start":"2026-07-10T14:48:43.755172Z","end":"2026-07-10T14:48:43.91359Z","steps":["trace[1807153987] 'read index received' (duration: 146.56387ms)","trace[1807153987] 'applied index is now lower than readState.Index' (duration: 11.852927ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T14:48:43.913649Z","caller":"traceutil/trace.go:171","msg":"trace[523934243] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"239.349634ms","start":"2026-07-10T14:48:43.674273Z","end":"2026-07-10T14:48:43.913623Z","steps":["trace[523934243] 'process raft request' (duration: 227.449318ms)","trace[523934243] 'compare' (duration: 11.77455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T14:48:43.913843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.651459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-07-10T14:48:43.913885Z","caller":"traceutil/trace.go:171","msg":"trace[1153623797] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1453; }","duration":"158.739046ms","start":"2026-07-10T14:48:43.755136Z","end":"2026-07-10T14:48:43.913875Z","steps":["trace[1153623797] 'agreement among raft nodes before linearized reading' (duration: 158.583041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.913901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.094432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-07-10T14:48:43.913937Z","caller":"traceutil/trace.go:171","msg":"trace[1263776236] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1453; }","duration":"143.169959ms","start":"2026-07-10T14:48:43.770757Z","end":"2026-07-10T14:48:43.913927Z","steps":["trace[1263776236] 'agreement among raft nodes before linearized reading' (duration: 143.045113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T14:48:43.914058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.299008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/\" range_end:\"/registry/services/specs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:1914"} {"level":"info","ts":"2026-07-10T14:48:43.914083Z","caller":"traceutil/trace.go:171","msg":"trace[784249439] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/; range_end:/registry/services/specs/envoy-gateway-system0; response_count:1; response_revision:1453; }","duration":"137.337087ms","start":"2026-07-10T14:48:43.776738Z","end":"2026-07-10T14:48:43.914075Z","steps":["trace[784249439] 'agreement among raft nodes before linearized reading' (duration: 137.25916ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T14:48:59.860632Z","caller":"traceutil/trace.go:171","msg":"trace[871355272] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"102.79256ms","start":"2026-07-10T14:48:59.757818Z","end":"2026-07-10T14:48:59.86061Z","steps":["trace[871355272] 'process raft request' (duration: 102.623314ms)"],"step_count":1}