{"level":"warn","ts":"2026-07-01T10:07:06.940758Z","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-01T10:07:06.940846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108: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.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-01T10:07:06.94102Z","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-01T10:07:06.941033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-07-01T10:07:06.941051Z","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-01T10:07:06.941649Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-07-01T10:07:06.941718Z","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.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-01T10:07:06.947844Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.92309ms"} {"level":"info","ts":"2026-07-01T10:07:06.961797Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-07-01T10:07:06.961877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T10:07:06.961933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-07-01T10:07:06.961946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T10:07:06.96196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-07-01T10:07:06.961999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-07-01T10:07:06.966438Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T10:07:06.967724Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T10:07:06.969858Z","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-01T10:07:06.971925Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T10:07:06.972187Z","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-01T10:07:06.972247Z","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-01T10:07:06.972256Z","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-01T10:07:06.972186Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T10:07:06.976236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-07-01T10:07:06.976473Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-07-01T10:07:06.978653Z","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-01T10:07:06.978794Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-07-01T10:07:06.978836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-07-01T10:07:06.978993Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T10:07:06.979036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T10:07:07.362763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T10:07:07.362912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T10:07:07.362951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-07-01T10:07:07.362972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-07-01T10:07:07.36299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-07-01T10:07:07.363006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-07-01T10:07:07.363027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-07-01T10:07:07.364199Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T10:07:07.364217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:07:07.364396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:07:07.364392Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:07:07.364477Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T10:07:07.36453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T10:07:07.36504Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:07:07.365128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:07:07.365148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:07:07.367611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"info","ts":"2026-07-01T10:07:07.367904Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-01T10:07:11.299104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.463428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:11.299242Z","caller":"traceutil/trace.go:171","msg":"trace[1146979821] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"161.860321ms","start":"2026-07-01T10:07:11.137363Z","end":"2026-07-01T10:07:11.299223Z","steps":["trace[1146979821] 'process raft request' (duration: 40.731393ms)","trace[1146979821] 'compare' (duration: 120.312546ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:16.354987Z","caller":"traceutil/trace.go:171","msg":"trace[1362282937] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"119.751345ms","start":"2026-07-01T10:07:16.235209Z","end":"2026-07-01T10:07:16.35496Z","steps":["trace[1362282937] 'read index received' (duration: 63.784853ms)","trace[1362282937] 'applied index is now lower than readState.Index' (duration: 55.965592ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:16.355127Z","caller":"traceutil/trace.go:171","msg":"trace[1927694357] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"144.684291ms","start":"2026-07-01T10:07:16.210408Z","end":"2026-07-01T10:07:16.355092Z","steps":["trace[1927694357] 'process raft request' (duration: 88.638223ms)","trace[1927694357] 'compare' (duration: 55.789019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:16.355415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.068959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-01T10:07:16.355467Z","caller":"traceutil/trace.go:171","msg":"trace[1277356113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:340; }","duration":"120.302707ms","start":"2026-07-01T10:07:16.23515Z","end":"2026-07-01T10:07:16.355453Z","steps":["trace[1277356113] 'agreement among raft nodes before linearized reading' (duration: 119.908357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:21.146957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.681131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:21.14707Z","caller":"traceutil/trace.go:171","msg":"trace[1509063669] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"127.633609ms","start":"2026-07-01T10:07:21.019418Z","end":"2026-07-01T10:07:21.147051Z","steps":["trace[1509063669] 'compare' (duration: 120.520799ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:21.327483Z","caller":"traceutil/trace.go:171","msg":"trace[944009626] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"138.121738ms","start":"2026-07-01T10:07:21.189316Z","end":"2026-07-01T10:07:21.327438Z","steps":["trace[944009626] 'process raft request' (duration: 62.762697ms)","trace[944009626] 'compare' (duration: 75.113482ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:29.93889Z","caller":"traceutil/trace.go:171","msg":"trace[675627420] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"137.020805ms","start":"2026-07-01T10:07:29.80185Z","end":"2026-07-01T10:07:29.938871Z","steps":["trace[675627420] 'process raft request' (duration: 136.897008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:31.990409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224804743402982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:07:32.490804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224804743402982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:07:32.827628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.610355557s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T10:07:32.828153Z","caller":"traceutil/trace.go:171","msg":"trace[21625543] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"1.610936737s","start":"2026-07-01T10:07:31.217188Z","end":"2026-07-01T10:07:32.828125Z","steps":["trace[21625543] 'process raft request' (duration: 1.610641962s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:32.828365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:31.217172Z","time spent":"1.611059875s","remote":"127.0.0.1:49246","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-01T10:07:33.235238Z","caller":"traceutil/trace.go:171","msg":"trace[303280139] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:452; }","duration":"1.744965293s","start":"2026-07-01T10:07:31.490239Z","end":"2026-07-01T10:07:33.235204Z","steps":["trace[303280139] 'read index received' (duration: 1.33756334s)","trace[303280139] 'applied index is now lower than readState.Index' (duration: 407.401003ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:33.235311Z","caller":"traceutil/trace.go:171","msg":"trace[805615552] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.052724331s","start":"2026-07-01T10:07:32.182536Z","end":"2026-07-01T10:07:33.23526Z","steps":["trace[805615552] 'process raft request' (duration: 1.028045254s)","trace[805615552] 'compare' (duration: 24.405002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:33.235622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106831304s","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-01T10:07:33.235648Z","caller":"traceutil/trace.go:171","msg":"trace[891550242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.106896563s","start":"2026-07-01T10:07:32.128743Z","end":"2026-07-01T10:07:33.235639Z","steps":["trace[891550242] 'agreement among raft nodes before linearized reading' (duration: 1.106823734s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:33.235627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745389246s","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-01T10:07:33.235669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:32.128728Z","time spent":"1.106935353s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:07:33.235716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:32.182511Z","time spent":"1.053116065s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:07:33.235711Z","caller":"traceutil/trace.go:171","msg":"trace[346993357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.745461535s","start":"2026-07-01T10:07:31.490219Z","end":"2026-07-01T10:07:33.235681Z","steps":["trace[346993357] 'agreement among raft nodes before linearized reading' (duration: 1.745306558s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:33.235771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.56222ms","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-01T10:07:33.23579Z","caller":"traceutil/trace.go:171","msg":"trace[1447960104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"792.619209ms","start":"2026-07-01T10:07:32.443165Z","end":"2026-07-01T10:07:33.235784Z","steps":["trace[1447960104] 'agreement among raft nodes before linearized reading' (duration: 792.58234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:33.235747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.179902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-07-01T10:07:33.235787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:31.490147Z","time spent":"1.745627993s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:07:33.235836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069407705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:07:33.235856Z","caller":"traceutil/trace.go:171","msg":"trace[671976160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"1.069464083s","start":"2026-07-01T10:07:32.166387Z","end":"2026-07-01T10:07:33.235851Z","steps":["trace[671976160] 'agreement among raft nodes before linearized reading' (duration: 1.069436114s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:33.23587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:32.166364Z","time spent":"1.069503253s","remote":"127.0.0.1:49022","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-01T10:07:33.235855Z","caller":"traceutil/trace.go:171","msg":"trace[2017393548] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:436; }","duration":"422.354539ms","start":"2026-07-01T10:07:32.813475Z","end":"2026-07-01T10:07:33.23583Z","steps":["trace[2017393548] 'agreement among raft nodes before linearized reading' (duration: 422.127793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:33.235814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:32.443143Z","time spent":"792.666898ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:07:33.235904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:32.813456Z","time spent":"422.439008ms","remote":"127.0.0.1:49466","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":"info","ts":"2026-07-01T10:07:34.94997Z","caller":"traceutil/trace.go:171","msg":"trace[1589673110] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"113.527298ms","start":"2026-07-01T10:07:34.836417Z","end":"2026-07-01T10:07:34.949944Z","steps":["trace[1589673110] 'process raft request' (duration: 113.36387ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:39.143688Z","caller":"traceutil/trace.go:171","msg":"trace[1797481553] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"173.597579ms","start":"2026-07-01T10:07:38.97007Z","end":"2026-07-01T10:07:39.143668Z","steps":["trace[1797481553] 'process raft request' (duration: 173.416671ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:39.489789Z","caller":"traceutil/trace.go:171","msg":"trace[858016256] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"194.028867ms","start":"2026-07-01T10:07:39.295736Z","end":"2026-07-01T10:07:39.489765Z","steps":["trace[858016256] 'process raft request' (duration: 193.855838ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:39.6617Z","caller":"traceutil/trace.go:171","msg":"trace[166385984] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"365.655117ms","start":"2026-07-01T10:07:39.296023Z","end":"2026-07-01T10:07:39.661678Z","steps":["trace[166385984] 'process raft request' (duration: 363.080831ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:39.661715Z","caller":"traceutil/trace.go:171","msg":"trace[1015861705] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"364.623347ms","start":"2026-07-01T10:07:39.297077Z","end":"2026-07-01T10:07:39.661701Z","steps":["trace[1015861705] 'process raft request' (duration: 364.538338ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:39.661701Z","caller":"traceutil/trace.go:171","msg":"trace[828712088] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"252.915888ms","start":"2026-07-01T10:07:39.408769Z","end":"2026-07-01T10:07:39.661685Z","steps":["trace[828712088] 'process raft request' (duration: 252.881958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:39.661813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:39.296014Z","time spent":"365.736166ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:07:39.661923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:39.297054Z","time spent":"364.703636ms","remote":"127.0.0.1:49246","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-01T10:07:45.648655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.899022ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T10:07:45.648902Z","caller":"traceutil/trace.go:171","msg":"trace[505129092] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"133.281377ms","start":"2026-07-01T10:07:45.515599Z","end":"2026-07-01T10:07:45.64888Z","steps":["trace[505129092] 'read index received' (duration: 25.099906ms)","trace[505129092] 'applied index is now lower than readState.Index' (duration: 108.179431ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:45.649173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.532516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-01T10:07:45.649339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.271355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-07-01T10:07:45.649401Z","caller":"traceutil/trace.go:171","msg":"trace[1282397127] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:525; }","duration":"118.343255ms","start":"2026-07-01T10:07:45.53104Z","end":"2026-07-01T10:07:45.649383Z","steps":["trace[1282397127] 'agreement among raft nodes before linearized reading' (duration: 117.973516ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:45.64927Z","caller":"traceutil/trace.go:171","msg":"trace[1826637756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"133.673396ms","start":"2026-07-01T10:07:45.515576Z","end":"2026-07-01T10:07:45.64925Z","steps":["trace[1826637756] 'agreement among raft nodes before linearized reading' (duration: 133.399176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:45.952168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.935566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:45.95227Z","caller":"traceutil/trace.go:171","msg":"trace[2052018993] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"237.678638ms","start":"2026-07-01T10:07:45.714575Z","end":"2026-07-01T10:07:45.952253Z","steps":["trace[2052018993] 'read index received' (duration: 115.601952ms)","trace[2052018993] 'applied index is now lower than readState.Index' (duration: 122.075546ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:45.952387Z","caller":"traceutil/trace.go:171","msg":"trace[412467898] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"259.457341ms","start":"2026-07-01T10:07:45.692882Z","end":"2026-07-01T10:07:45.952339Z","steps":["trace[412467898] 'process raft request' (duration: 137.262656ms)","trace[412467898] 'compare' (duration: 121.810216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:45.952439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.863547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-07-01T10:07:45.95247Z","caller":"traceutil/trace.go:171","msg":"trace[122819711] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:528; }","duration":"237.926457ms","start":"2026-07-01T10:07:45.714533Z","end":"2026-07-01T10:07:45.952459Z","steps":["trace[122819711] 'agreement among raft nodes before linearized reading' (duration: 237.803677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:45.952841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.637046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-07-01T10:07:45.952911Z","caller":"traceutil/trace.go:171","msg":"trace[2013834359] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:528; }","duration":"191.776246ms","start":"2026-07-01T10:07:45.761118Z","end":"2026-07-01T10:07:45.952894Z","steps":["trace[2013834359] 'agreement among raft nodes before linearized reading' (duration: 191.263087ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:47.646569Z","caller":"traceutil/trace.go:171","msg":"trace[1503185691] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"460.70787ms","start":"2026-07-01T10:07:47.185835Z","end":"2026-07-01T10:07:47.646543Z","steps":["trace[1503185691] 'process raft request' (duration: 460.50992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:47.646726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.185811Z","time spent":"460.83604ms","remote":"127.0.0.1:49246","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-01T10:07:48.138419Z","caller":"traceutil/trace.go:171","msg":"trace[1800293555] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"663.635258ms","start":"2026-07-01T10:07:47.474756Z","end":"2026-07-01T10:07:48.138392Z","steps":["trace[1800293555] 'process raft request' (duration: 663.509898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.138547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.474736Z","time spent":"663.752398ms","remote":"127.0.0.1:49046","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-01T10:07:48.13865Z","caller":"traceutil/trace.go:171","msg":"trace[99570656] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"668.915606ms","start":"2026-07-01T10:07:47.469727Z","end":"2026-07-01T10:07:48.138643Z","steps":["trace[99570656] 'process raft request' (duration: 668.379706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.13868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.469707Z","time spent":"668.956596ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:07:48.138735Z","caller":"traceutil/trace.go:171","msg":"trace[321358989] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"633.706581ms","start":"2026-07-01T10:07:47.505023Z","end":"2026-07-01T10:07:48.138729Z","steps":["trace[321358989] 'process raft request' (duration: 633.35856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.13877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.505015Z","time spent":"633.731301ms","remote":"127.0.0.1:49046","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-01T10:07:48.138775Z","caller":"traceutil/trace.go:171","msg":"trace[671316403] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"624.383355ms","start":"2026-07-01T10:07:47.514366Z","end":"2026-07-01T10:07:48.138749Z","steps":["trace[671316403] 'process raft request' (duration: 624.123074ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:48.138816Z","caller":"traceutil/trace.go:171","msg":"trace[477664848] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"663.984919ms","start":"2026-07-01T10:07:47.474826Z","end":"2026-07-01T10:07:48.138811Z","steps":["trace[477664848] 'process raft request' (duration: 663.475558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.138841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.474819Z","time spent":"664.011499ms","remote":"127.0.0.1:49046","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-01T10:07:48.138885Z","caller":"traceutil/trace.go:171","msg":"trace[1028519234] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"637.68635ms","start":"2026-07-01T10:07:47.501193Z","end":"2026-07-01T10:07:48.138879Z","steps":["trace[1028519234] 'process raft request' (duration: 637.149839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.138884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.514356Z","time spent":"624.468105ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:07:48.138914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.501177Z","time spent":"637.72537ms","remote":"127.0.0.1:49046","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-01T10:07:48.138979Z","caller":"traceutil/trace.go:171","msg":"trace[487009175] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"628.251663ms","start":"2026-07-01T10:07:47.51072Z","end":"2026-07-01T10:07:48.138972Z","steps":["trace[487009175] 'process raft request' (duration: 627.698452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.139013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.510708Z","time spent":"628.291263ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:07:48.139143Z","caller":"traceutil/trace.go:171","msg":"trace[1172389804] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"616.371528ms","start":"2026-07-01T10:07:47.522743Z","end":"2026-07-01T10:07:48.139115Z","steps":["trace[1172389804] 'process raft request' (duration: 615.783957ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:48.139293Z","caller":"traceutil/trace.go:171","msg":"trace[440753212] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"606.264512ms","start":"2026-07-01T10:07:47.53302Z","end":"2026-07-01T10:07:48.139285Z","steps":["trace[440753212] 'process raft request' (duration: 605.595151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.139292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.52273Z","time spent":"616.493998ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:07:48.139338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.533004Z","time spent":"606.317092ms","remote":"127.0.0.1:49046","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-01T10:07:48.143452Z","caller":"traceutil/trace.go:171","msg":"trace[509789400] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"586.118626ms","start":"2026-07-01T10:07:47.55732Z","end":"2026-07-01T10:07:48.143438Z","steps":["trace[509789400] 'process raft request' (duration: 586.058916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.143525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.557302Z","time spent":"586.188606ms","remote":"127.0.0.1:49046","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-01T10:07:48.143594Z","caller":"traceutil/trace.go:171","msg":"trace[1072282431] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"596.765491ms","start":"2026-07-01T10:07:47.546823Z","end":"2026-07-01T10:07:48.143588Z","steps":["trace[1072282431] 'process raft request' (duration: 591.848317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.14362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.546802Z","time spent":"596.805141ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:07:48.155983Z","caller":"traceutil/trace.go:171","msg":"trace[861702174] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:556; }","duration":"464.525369ms","start":"2026-07-01T10:07:47.69144Z","end":"2026-07-01T10:07:48.155965Z","steps":["trace[861702174] 'read index received' (duration: 446.925717ms)","trace[861702174] 'applied index is now lower than readState.Index' (duration: 17.596582ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:48.156183Z","caller":"traceutil/trace.go:171","msg":"trace[1806596507] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"490.944518ms","start":"2026-07-01T10:07:47.665231Z","end":"2026-07-01T10:07:48.156176Z","steps":["trace[1806596507] 'process raft request' (duration: 490.629957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.15624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.665195Z","time spent":"490.997268ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:07:48.15633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.849839ms","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-01T10:07:48.15638Z","caller":"traceutil/trace.go:171","msg":"trace[928793927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"464.950719ms","start":"2026-07-01T10:07:47.691418Z","end":"2026-07-01T10:07:48.156369Z","steps":["trace[928793927] 'agreement among raft nodes before linearized reading' (duration: 464.793699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.156416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.691407Z","time spent":"465.003839ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:07:48.160779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.272131ms","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-01T10:07:48.160895Z","caller":"traceutil/trace.go:171","msg":"trace[726523310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"447.372981ms","start":"2026-07-01T10:07:47.713455Z","end":"2026-07-01T10:07:48.160828Z","steps":["trace[726523310] 'agreement among raft nodes before linearized reading' (duration: 447.275381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:48.160926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:47.713433Z","time spent":"447.484721ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:07:48.161262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.571921ms","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-01T10:07:48.161349Z","caller":"traceutil/trace.go:171","msg":"trace[520418897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"204.632781ms","start":"2026-07-01T10:07:47.956673Z","end":"2026-07-01T10:07:48.161305Z","steps":["trace[520418897] 'agreement among raft nodes before linearized reading' (duration: 204.514211ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:48.903357Z","caller":"traceutil/trace.go:171","msg":"trace[1680788987] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"168.850428ms","start":"2026-07-01T10:07:48.734451Z","end":"2026-07-01T10:07:48.903302Z","steps":["trace[1680788987] 'process raft request' (duration: 105.128176ms)","trace[1680788987] 'compare' (duration: 63.260491ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:48.903202Z","caller":"traceutil/trace.go:171","msg":"trace[1309553372] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"162.758196ms","start":"2026-07-01T10:07:48.740415Z","end":"2026-07-01T10:07:48.903173Z","steps":["trace[1309553372] 'process raft request' (duration: 162.596596ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:48.937152Z","caller":"traceutil/trace.go:171","msg":"trace[1950406816] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"101.335395ms","start":"2026-07-01T10:07:48.835795Z","end":"2026-07-01T10:07:48.937131Z","steps":["trace[1950406816] 'process raft request' (duration: 101.205145ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:49.048347Z","caller":"traceutil/trace.go:171","msg":"trace[912671235] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"132.069351ms","start":"2026-07-01T10:07:48.916255Z","end":"2026-07-01T10:07:49.048325Z","steps":["trace[912671235] 'process raft request' (duration: 107.395499ms)","trace[912671235] 'compare' (duration: 24.552732ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:49.048926Z","caller":"traceutil/trace.go:171","msg":"trace[505161210] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"100.246531ms","start":"2026-07-01T10:07:48.948658Z","end":"2026-07-01T10:07:49.048905Z","steps":["trace[505161210] 'process raft request' (duration: 100.05331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:49.385655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.751161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:49.385762Z","caller":"traceutil/trace.go:171","msg":"trace[2067380402] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"227.138772ms","start":"2026-07-01T10:07:49.158605Z","end":"2026-07-01T10:07:49.385744Z","steps":["trace[2067380402] 'process raft request' (duration: 119.221901ms)","trace[2067380402] 'compare' (duration: 107.453361ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:49.397896Z","caller":"traceutil/trace.go:171","msg":"trace[888425743] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"167.850133ms","start":"2026-07-01T10:07:49.229991Z","end":"2026-07-01T10:07:49.397841Z","steps":["trace[888425743] 'process raft request' (duration: 165.042148ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:49.399555Z","caller":"traceutil/trace.go:171","msg":"trace[3184118] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"165.367349ms","start":"2026-07-01T10:07:49.234152Z","end":"2026-07-01T10:07:49.39952Z","steps":["trace[3184118] 'process raft request' (duration: 162.852704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:49.692513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.735832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:49.692596Z","caller":"traceutil/trace.go:171","msg":"trace[1120746485] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"253.459027ms","start":"2026-07-01T10:07:49.439123Z","end":"2026-07-01T10:07:49.692582Z","steps":["trace[1120746485] 'read index received' (duration: 145.449005ms)","trace[1120746485] 'applied index is now lower than readState.Index' (duration: 108.009242ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:49.692745Z","caller":"traceutil/trace.go:171","msg":"trace[1677027680] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"257.217953ms","start":"2026-07-01T10:07:49.435519Z","end":"2026-07-01T10:07:49.692737Z","steps":["trace[1677027680] 'process raft request' (duration: 149.182111ms)","trace[1677027680] 'compare' (duration: 107.623671ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:49.693004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.887488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-07-01T10:07:49.693027Z","caller":"traceutil/trace.go:171","msg":"trace[955793109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"253.911279ms","start":"2026-07-01T10:07:49.439108Z","end":"2026-07-01T10:07:49.693019Z","steps":["trace[955793109] 'agreement among raft nodes before linearized reading' (duration: 253.783808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:49.972344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.326461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:49.972501Z","caller":"traceutil/trace.go:171","msg":"trace[1951745231] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"197.262993ms","start":"2026-07-01T10:07:49.775213Z","end":"2026-07-01T10:07:49.972476Z","steps":["trace[1951745231] 'process raft request' (duration: 53.673971ms)","trace[1951745231] 'compare' (duration: 143.135491ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:49.978148Z","caller":"traceutil/trace.go:171","msg":"trace[947323490] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"161.688593ms","start":"2026-07-01T10:07:49.816438Z","end":"2026-07-01T10:07:49.978127Z","steps":["trace[947323490] 'read index received' (duration: 12.569242ms)","trace[947323490] 'applied index is now lower than readState.Index' (duration: 149.118361ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:49.978237Z","caller":"traceutil/trace.go:171","msg":"trace[117866588] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"196.912272ms","start":"2026-07-01T10:07:49.781287Z","end":"2026-07-01T10:07:49.978199Z","steps":["trace[117866588] 'process raft request' (duration: 196.597842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:49.978439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.970594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-07-01T10:07:49.97852Z","caller":"traceutil/trace.go:171","msg":"trace[760240946] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:609; }","duration":"162.112534ms","start":"2026-07-01T10:07:49.816387Z","end":"2026-07-01T10:07:49.978499Z","steps":["trace[760240946] 'agreement among raft nodes before linearized reading' (duration: 161.827623ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:54.852195Z","caller":"traceutil/trace.go:171","msg":"trace[1688986874] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"565.028387ms","start":"2026-07-01T10:07:54.287136Z","end":"2026-07-01T10:07:54.852165Z","steps":["trace[1688986874] 'process raft request' (duration: 564.364303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:54.852448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:54.287111Z","time spent":"565.163149ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:07:55.515064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224804743403356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:07:55.614693Z","caller":"traceutil/trace.go:171","msg":"trace[770058711] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"599.752477ms","start":"2026-07-01T10:07:55.014913Z","end":"2026-07-01T10:07:55.614665Z","steps":["trace[770058711] 'read index received' (duration: 599.710157ms)","trace[770058711] 'applied index is now lower than readState.Index' (duration: 41.32µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:07:55.614773Z","caller":"traceutil/trace.go:171","msg":"trace[292683193] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"709.513526ms","start":"2026-07-01T10:07:54.905222Z","end":"2026-07-01T10:07:55.614736Z","steps":["trace[292683193] 'process raft request' (duration: 709.296175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:55.614921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:54.905194Z","time spent":"709.655817ms","remote":"127.0.0.1:49366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:07:55.615043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.10471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qj64f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T10:07:55.615172Z","caller":"traceutil/trace.go:171","msg":"trace[383425605] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qj64f; range_end:; response_count:1; response_revision:641; }","duration":"600.2174ms","start":"2026-07-01T10:07:55.014875Z","end":"2026-07-01T10:07:55.615093Z","steps":["trace[383425605] 'agreement among raft nodes before linearized reading' (duration: 599.919018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:55.61525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:55.014859Z","time spent":"600.377111ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-qj64f\" "} {"level":"warn","ts":"2026-07-01T10:07:56.211836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.84317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T10:07:56.211951Z","caller":"traceutil/trace.go:171","msg":"trace[1082418976] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"597.161789ms","start":"2026-07-01T10:07:55.614767Z","end":"2026-07-01T10:07:56.211929Z","steps":["trace[1082418976] 'read index received' (duration: 14.162347ms)","trace[1082418976] 'applied index is now lower than readState.Index' (duration: 582.997962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:56.212219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045333854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:07:56.212373Z","caller":"traceutil/trace.go:171","msg":"trace[308192863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.045504396s","start":"2026-07-01T10:07:55.166805Z","end":"2026-07-01T10:07:56.21231Z","steps":["trace[308192863] 'agreement among raft nodes before linearized reading' (duration: 1.045214664s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:56.212454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:55.166782Z","time spent":"1.045658387s","remote":"127.0.0.1:49022","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-01T10:07:56.312604Z","caller":"traceutil/trace.go:171","msg":"trace[69424573] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"100.595518ms","start":"2026-07-01T10:07:56.211983Z","end":"2026-07-01T10:07:56.312579Z","steps":["trace[69424573] 'read index received' (duration: 100.328175ms)","trace[69424573] 'applied index is now lower than readState.Index' (duration: 266.353µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:56.312886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.158748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T10:07:56.31292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.614242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T10:07:56.312956Z","caller":"traceutil/trace.go:171","msg":"trace[1534310652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"111.27878ms","start":"2026-07-01T10:07:56.201663Z","end":"2026-07-01T10:07:56.312942Z","steps":["trace[1534310652] 'agreement among raft nodes before linearized reading' (duration: 110.994767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:56.312962Z","caller":"traceutil/trace.go:171","msg":"trace[1891757839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"105.703362ms","start":"2026-07-01T10:07:56.207245Z","end":"2026-07-01T10:07:56.312948Z","steps":["trace[1891757839] 'agreement among raft nodes before linearized reading' (duration: 105.592501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:56.312909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.528487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:07:56.312882Z","caller":"traceutil/trace.go:171","msg":"trace[259827907] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"682.948455ms","start":"2026-07-01T10:07:55.6299Z","end":"2026-07-01T10:07:56.312848Z","steps":["trace[259827907] 'process raft request' (duration: 682.486981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:56.312975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.313808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:07:56.313109Z","caller":"traceutil/trace.go:171","msg":"trace[1046746048] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:642; }","duration":"687.45528ms","start":"2026-07-01T10:07:55.625636Z","end":"2026-07-01T10:07:56.313091Z","steps":["trace[1046746048] 'agreement among raft nodes before linearized reading' (duration: 687.313889ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:56.313077Z","caller":"traceutil/trace.go:171","msg":"trace[1542443571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"530.692738ms","start":"2026-07-01T10:07:55.782323Z","end":"2026-07-01T10:07:56.313016Z","steps":["trace[1542443571] 'agreement among raft nodes before linearized reading' (duration: 530.412536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:56.313161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:55.625614Z","time spent":"687.534851ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-07-01T10:07:56.313051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.194034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-01T10:07:56.313191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:55.782307Z","time spent":"530.85367ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:07:56.313315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:55.629864Z","time spent":"683.193288ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:07:56.313339Z","caller":"traceutil/trace.go:171","msg":"trace[677513058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"121.435726ms","start":"2026-07-01T10:07:56.191767Z","end":"2026-07-01T10:07:56.313203Z","steps":["trace[677513058] 'agreement among raft nodes before linearized reading' (duration: 120.924912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:58.322916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999566652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-01T10:07:58.323076Z","caller":"traceutil/trace.go:171","msg":"trace[1512020465] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999785764s","start":"2026-07-01T10:07:56.323271Z","end":"2026-07-01T10:07:58.323057Z","steps":["trace[1512020465] 'agreement among raft nodes before linearized reading' (duration: 1.999605992s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:58.323185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:56.323246Z","time spent":"1.999925855s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/01 10:07:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-01T10:07:58.581071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297198755s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T10:07:58.582288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299512588s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:07:58.58254Z","caller":"traceutil/trace.go:171","msg":"trace[2141488397] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.262270805s","start":"2026-07-01T10:07:56.32025Z","end":"2026-07-01T10:07:58.582521Z","steps":["trace[2141488397] 'process raft request' (duration: 2.262124794s)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:58.582524Z","caller":"traceutil/trace.go:171","msg":"trace[817919220] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.262359036s","start":"2026-07-01T10:07:56.320137Z","end":"2026-07-01T10:07:58.582496Z","steps":["trace[817919220] 'process raft request' (duration: 962.553655ms)","trace[817919220] 'compare' (duration: 1.298619309s)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:58.582626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:56.320238Z","time spent":"2.262348836s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:07:58.582672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:56.320083Z","time spent":"2.262514327s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:07:58.582781Z","caller":"traceutil/trace.go:171","msg":"trace[1879694908] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.262233806s","start":"2026-07-01T10:07:56.320506Z","end":"2026-07-01T10:07:58.582739Z","steps":["trace[1879694908] 'process raft request' (duration: 2.261966493s)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:58.582884Z","caller":"traceutil/trace.go:171","msg":"trace[2041672341] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:670; }","duration":"2.259532293s","start":"2026-07-01T10:07:56.323322Z","end":"2026-07-01T10:07:58.582854Z","steps":["trace[2041672341] 'read index received' (duration: 17.235757ms)","trace[2041672341] 'applied index is now lower than readState.Index' (duration: 2.242294376s)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:07:58.582976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:56.320498Z","time spent":"2.262376887s","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:07:58.584045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.88384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:07:58.584159Z","caller":"traceutil/trace.go:171","msg":"trace[690186282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"261.070542ms","start":"2026-07-01T10:07:58.323064Z","end":"2026-07-01T10:07:58.584135Z","steps":["trace[690186282] 'agreement among raft nodes before linearized reading' (duration: 260.83822ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:07:58.58422Z","caller":"traceutil/trace.go:171","msg":"trace[1299838184] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.876462508s","start":"2026-07-01T10:07:56.707734Z","end":"2026-07-01T10:07:58.584197Z","steps":["trace[1299838184] 'process raft request' (duration: 1.875221635s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:07:58.584328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:07:56.707703Z","time spent":"1.876567978s","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:07:58.585378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.967813ms","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-01T10:07:58.585505Z","caller":"traceutil/trace.go:171","msg":"trace[1753684086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"240.125506ms","start":"2026-07-01T10:07:58.34533Z","end":"2026-07-01T10:07:58.585455Z","steps":["trace[1753684086] 'agreement among raft nodes before linearized reading' (duration: 239.160326ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:00.578179Z","caller":"traceutil/trace.go:171","msg":"trace[605143504] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"253.548152ms","start":"2026-07-01T10:08:00.324603Z","end":"2026-07-01T10:08:00.578151Z","steps":["trace[605143504] 'process raft request' (duration: 253.34439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:14.667666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224804743403691,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:08:14.737575Z","caller":"traceutil/trace.go:171","msg":"trace[533315163] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"746.923559ms","start":"2026-07-01T10:08:13.990632Z","end":"2026-07-01T10:08:14.737556Z","steps":["trace[533315163] 'process raft request' (duration: 746.805886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:14.737769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:08:13.990606Z","time spent":"747.087553ms","remote":"127.0.0.1:49246","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-01T10:08:14.747706Z","caller":"traceutil/trace.go:171","msg":"trace[273401720] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"580.056166ms","start":"2026-07-01T10:08:14.167622Z","end":"2026-07-01T10:08:14.747678Z","steps":["trace[273401720] 'read index received' (duration: 569.80673ms)","trace[273401720] 'applied index is now lower than readState.Index' (duration: 10.248366ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:08:14.747998Z","caller":"traceutil/trace.go:171","msg":"trace[1577311011] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"606.427184ms","start":"2026-07-01T10:08:14.141556Z","end":"2026-07-01T10:08:14.747984Z","steps":["trace[1577311011] 'process raft request' (duration: 605.964653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:14.748166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:08:14.141537Z","time spent":"606.517755ms","remote":"127.0.0.1:49216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:08:14.748314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.488797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:08:14.748371Z","caller":"traceutil/trace.go:171","msg":"trace[397791484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"580.761802ms","start":"2026-07-01T10:08:14.167592Z","end":"2026-07-01T10:08:14.748354Z","steps":["trace[397791484] 'agreement among raft nodes before linearized reading' (duration: 580.465515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:14.748601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:08:14.167581Z","time spent":"581.012608ms","remote":"127.0.0.1:49022","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-01T10:08:14.74885Z","caller":"traceutil/trace.go:171","msg":"trace[447113730] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"395.047656ms","start":"2026-07-01T10:08:14.35285Z","end":"2026-07-01T10:08:14.747897Z","steps":["trace[447113730] 'process raft request' (duration: 394.78747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:14.748969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:08:14.352796Z","time spent":"396.098761ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:08:22.93925Z","caller":"traceutil/trace.go:171","msg":"trace[2139486039] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"169.277704ms","start":"2026-07-01T10:08:22.769942Z","end":"2026-07-01T10:08:22.93922Z","steps":["trace[2139486039] 'read index received' (duration: 169.048838ms)","trace[2139486039] 'applied index is now lower than readState.Index' (duration: 227.666µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:08:22.93928Z","caller":"traceutil/trace.go:171","msg":"trace[446379096] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"204.797098ms","start":"2026-07-01T10:08:22.734453Z","end":"2026-07-01T10:08:22.93925Z","steps":["trace[446379096] 'process raft request' (duration: 204.591242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:22.939713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.821513ms","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-01T10:08:22.939748Z","caller":"traceutil/trace.go:171","msg":"trace[1444282650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"161.890285ms","start":"2026-07-01T10:08:22.777849Z","end":"2026-07-01T10:08:22.93974Z","steps":["trace[1444282650] 'agreement among raft nodes before linearized reading' (duration: 161.818393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:22.93994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.40925ms","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-01T10:08:22.939963Z","caller":"traceutil/trace.go:171","msg":"trace[1823777785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"150.454732ms","start":"2026-07-01T10:08:22.789502Z","end":"2026-07-01T10:08:22.939957Z","steps":["trace[1823777785] 'agreement among raft nodes before linearized reading' (duration: 150.39639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:22.94003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.349751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:08:22.940124Z","caller":"traceutil/trace.go:171","msg":"trace[871926211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"149.475794ms","start":"2026-07-01T10:08:22.790618Z","end":"2026-07-01T10:08:22.940094Z","steps":["trace[871926211] 'agreement among raft nodes before linearized reading' (duration: 149.206706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:08:22.939525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.559032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-01T10:08:22.940126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.533676ms","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-01T10:08:22.940183Z","caller":"traceutil/trace.go:171","msg":"trace[1431597276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"170.283602ms","start":"2026-07-01T10:08:22.769893Z","end":"2026-07-01T10:08:22.940176Z","steps":["trace[1431597276] 'agreement among raft nodes before linearized reading' (duration: 169.472769ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:22.940273Z","caller":"traceutil/trace.go:171","msg":"trace[1026677181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"149.713101ms","start":"2026-07-01T10:08:22.790532Z","end":"2026-07-01T10:08:22.940245Z","steps":["trace[1026677181] 'agreement among raft nodes before linearized reading' (duration: 149.220937ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:43.229879Z","caller":"traceutil/trace.go:171","msg":"trace[60964426] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"123.236015ms","start":"2026-07-01T10:08:43.106621Z","end":"2026-07-01T10:08:43.229857Z","steps":["trace[60964426] 'process raft request' (duration: 103.787352ms)","trace[60964426] 'compare' (duration: 19.220084ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:08:43.232756Z","caller":"traceutil/trace.go:171","msg":"trace[41062709] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"125.186053ms","start":"2026-07-01T10:08:43.107552Z","end":"2026-07-01T10:08:43.232738Z","steps":["trace[41062709] 'process raft request' (duration: 125.060088ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:43.232773Z","caller":"traceutil/trace.go:171","msg":"trace[1405410081] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"125.62192ms","start":"2026-07-01T10:08:43.107118Z","end":"2026-07-01T10:08:43.23274Z","steps":["trace[1405410081] 'process raft request' (duration: 125.417012ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:44.204081Z","caller":"traceutil/trace.go:171","msg":"trace[878470222] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"105.767677ms","start":"2026-07-01T10:08:44.09828Z","end":"2026-07-01T10:08:44.204048Z","steps":["trace[878470222] 'process raft request' (duration: 105.566839ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:47.424946Z","caller":"traceutil/trace.go:171","msg":"trace[155103529] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"148.489612ms","start":"2026-07-01T10:08:47.276423Z","end":"2026-07-01T10:08:47.424913Z","steps":["trace[155103529] 'process raft request' (duration: 148.437239ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:08:47.424975Z","caller":"traceutil/trace.go:171","msg":"trace[841537000] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"149.417389ms","start":"2026-07-01T10:08:47.275526Z","end":"2026-07-01T10:08:47.424944Z","steps":["trace[841537000] 'process raft request' (duration: 141.510465ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:09.634957Z","caller":"traceutil/trace.go:171","msg":"trace[1723381400] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"268.509294ms","start":"2026-07-01T10:09:09.366424Z","end":"2026-07-01T10:09:09.634933Z","steps":["trace[1723381400] 'process raft request' (duration: 268.387268ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:09.635665Z","caller":"traceutil/trace.go:171","msg":"trace[157068195] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"267.661733ms","start":"2026-07-01T10:09:09.367992Z","end":"2026-07-01T10:09:09.635654Z","steps":["trace[157068195] 'process raft request' (duration: 267.615851ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:19.805396Z","caller":"traceutil/trace.go:171","msg":"trace[996422027] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"122.001267ms","start":"2026-07-01T10:09:19.683374Z","end":"2026-07-01T10:09:19.805375Z","steps":["trace[996422027] 'process raft request' (duration: 121.859339ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:19.8054Z","caller":"traceutil/trace.go:171","msg":"trace[984293531] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"123.449471ms","start":"2026-07-01T10:09:19.681913Z","end":"2026-07-01T10:09:19.805362Z","steps":["trace[984293531] 'process raft request' (duration: 66.425532ms)","trace[984293531] 'compare' (duration: 56.605298ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:09:19.805336Z","caller":"traceutil/trace.go:171","msg":"trace[1300067811] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1205; }","duration":"120.662388ms","start":"2026-07-01T10:09:19.684641Z","end":"2026-07-01T10:09:19.805303Z","steps":["trace[1300067811] 'read index received' (duration: 1.063125ms)","trace[1300067811] 'applied index is now lower than readState.Index' (duration: 119.597873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:09:19.805557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.89237ms","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-01T10:09:19.80561Z","caller":"traceutil/trace.go:171","msg":"trace[1001700563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"120.974574ms","start":"2026-07-01T10:09:19.684624Z","end":"2026-07-01T10:09:19.805598Z","steps":["trace[1001700563] 'agreement among raft nodes before linearized reading' (duration: 120.773594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:09:19.805828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.664203ms","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-01T10:09:19.805878Z","caller":"traceutil/trace.go:171","msg":"trace[368491210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"117.77491ms","start":"2026-07-01T10:09:19.688088Z","end":"2026-07-01T10:09:19.805863Z","steps":["trace[368491210] 'agreement among raft nodes before linearized reading' (duration: 117.531397ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:23.797054Z","caller":"traceutil/trace.go:171","msg":"trace[59836887] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"101.495822ms","start":"2026-07-01T10:09:23.695537Z","end":"2026-07-01T10:09:23.797033Z","steps":["trace[59836887] 'read index received' (duration: 101.317742ms)","trace[59836887] 'applied index is now lower than readState.Index' (duration: 177.2µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:09:23.797299Z","caller":"traceutil/trace.go:171","msg":"trace[75692900] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"106.370866ms","start":"2026-07-01T10:09:23.690898Z","end":"2026-07-01T10:09:23.797269Z","steps":["trace[75692900] 'process raft request' (duration: 106.007167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:09:23.7977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.142455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:09:23.797749Z","caller":"traceutil/trace.go:171","msg":"trace[1535687148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"102.225549ms","start":"2026-07-01T10:09:23.695511Z","end":"2026-07-01T10:09:23.797737Z","steps":["trace[1535687148] 'agreement among raft nodes before linearized reading' (duration: 101.624848ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:09:26.070211Z","caller":"traceutil/trace.go:171","msg":"trace[1271220256] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"116.846094ms","start":"2026-07-01T10:09:25.953346Z","end":"2026-07-01T10:09:26.070192Z","steps":["trace[1271220256] 'process raft request' (duration: 39.004439ms)","trace[1271220256] 'compare' (duration: 77.350739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:10:05.750032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.960348ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-01T10:10:06.725065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224804743405013,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:10:07.016929Z","caller":"traceutil/trace.go:171","msg":"trace[627328881] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"792.894046ms","start":"2026-07-01T10:10:06.224012Z","end":"2026-07-01T10:10:07.016906Z","steps":["trace[627328881] 'read index received' (duration: 792.743487ms)","trace[627328881] 'applied index is now lower than readState.Index' (duration: 149.529µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:10:07.017022Z","caller":"traceutil/trace.go:171","msg":"trace[1135215680] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"858.703892ms","start":"2026-07-01T10:10:06.158282Z","end":"2026-07-01T10:10:07.016985Z","steps":["trace[1135215680] 'process raft request' (duration: 858.435366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.017201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.158256Z","time spent":"858.848561ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:10:07.017351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.790597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-01T10:10:07.017359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.209196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:10:07.017382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.063221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T10:10:07.017174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.556825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:10:07.017458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.195594ms","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-01T10:10:07.017557Z","caller":"traceutil/trace.go:171","msg":"trace[1073558702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"734.342752ms","start":"2026-07-01T10:10:06.283195Z","end":"2026-07-01T10:10:07.017538Z","steps":["trace[1073558702] 'agreement among raft nodes before linearized reading' (duration: 734.011392ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.01749Z","caller":"traceutil/trace.go:171","msg":"trace[348476097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"777.309216ms","start":"2026-07-01T10:10:06.240164Z","end":"2026-07-01T10:10:07.017473Z","steps":["trace[348476097] 'agreement among raft nodes before linearized reading' (duration: 776.883251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.017609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.28318Z","time spent":"734.420107ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T10:10:07.017405Z","caller":"traceutil/trace.go:171","msg":"trace[349795139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"746.873511ms","start":"2026-07-01T10:10:06.270514Z","end":"2026-07-01T10:10:07.017388Z","steps":["trace[349795139] 'agreement among raft nodes before linearized reading' (duration: 746.705311ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.017496Z","caller":"traceutil/trace.go:171","msg":"trace[1573577837] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1347; }","duration":"613.939057ms","start":"2026-07-01T10:10:06.403549Z","end":"2026-07-01T10:10:07.017488Z","steps":["trace[1573577837] 'agreement among raft nodes before linearized reading' (duration: 613.539073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.017697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.240137Z","time spent":"777.481137ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:10:07.017671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.270494Z","time spent":"747.161798ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:10:07.017727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.403531Z","time spent":"614.184552ms","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T10:10:07.017686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.231444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-01T10:10:07.017451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.061683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:10:07.017903Z","caller":"traceutil/trace.go:171","msg":"trace[1954804873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"739.513262ms","start":"2026-07-01T10:10:06.278321Z","end":"2026-07-01T10:10:07.017834Z","steps":["trace[1954804873] 'agreement among raft nodes before linearized reading' (duration: 738.888225ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.017981Z","caller":"traceutil/trace.go:171","msg":"trace[318550506] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1347; }","duration":"498.491318ms","start":"2026-07-01T10:10:06.519351Z","end":"2026-07-01T10:10:07.017842Z","steps":["trace[318550506] 'agreement among raft nodes before linearized reading' (duration: 498.081494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.01801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.519333Z","time spent":"498.667949ms","remote":"127.0.0.1:49216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-01T10:10:07.018184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.180763ms","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-01T10:10:07.018213Z","caller":"traceutil/trace.go:171","msg":"trace[1309804576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"794.245337ms","start":"2026-07-01T10:10:06.22396Z","end":"2026-07-01T10:10:07.018206Z","steps":["trace[1309804576] 'agreement among raft nodes before linearized reading' (duration: 793.190284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.018231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.223939Z","time spent":"794.287929ms","remote":"127.0.0.1:49246","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-01T10:10:07.017419Z","caller":"traceutil/trace.go:171","msg":"trace[1932961766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"235.310793ms","start":"2026-07-01T10:10:06.782088Z","end":"2026-07-01T10:10:07.017399Z","steps":["trace[1932961766] 'agreement among raft nodes before linearized reading' (duration: 235.220328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.018971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:10:06.278309Z","time spent":"739.673851ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T10:10:07.313978Z","caller":"traceutil/trace.go:171","msg":"trace[1894032960] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1408; }","duration":"146.611606ms","start":"2026-07-01T10:10:07.167344Z","end":"2026-07-01T10:10:07.313956Z","steps":["trace[1894032960] 'read index received' (duration: 146.372192ms)","trace[1894032960] 'applied index is now lower than readState.Index' (duration: 238.234µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:10:07.314002Z","caller":"traceutil/trace.go:171","msg":"trace[1735691110] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"290.859271ms","start":"2026-07-01T10:10:07.023107Z","end":"2026-07-01T10:10:07.313966Z","steps":["trace[1735691110] 'process raft request' (duration: 290.732904ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.314024Z","caller":"traceutil/trace.go:171","msg":"trace[557515263] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"290.118707ms","start":"2026-07-01T10:10:07.023876Z","end":"2026-07-01T10:10:07.313995Z","steps":["trace[557515263] 'process raft request' (duration: 290.00142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:10:07.314096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.733583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:10:07.314129Z","caller":"traceutil/trace.go:171","msg":"trace[1679524406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"146.813928ms","start":"2026-07-01T10:10:07.167305Z","end":"2026-07-01T10:10:07.314119Z","steps":["trace[1679524406] 'agreement among raft nodes before linearized reading' (duration: 146.746724ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.314139Z","caller":"traceutil/trace.go:171","msg":"trace[1493773341] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"289.967088ms","start":"2026-07-01T10:10:07.024163Z","end":"2026-07-01T10:10:07.31413Z","steps":["trace[1493773341] 'process raft request' (duration: 289.746395ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.314156Z","caller":"traceutil/trace.go:171","msg":"trace[176503177] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"291.039722ms","start":"2026-07-01T10:10:07.023077Z","end":"2026-07-01T10:10:07.314117Z","steps":["trace[176503177] 'process raft request' (duration: 290.620587ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:10:07.788364Z","caller":"traceutil/trace.go:171","msg":"trace[1093641154] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"101.582394ms","start":"2026-07-01T10:10:07.686759Z","end":"2026-07-01T10:10:07.788341Z","steps":["trace[1093641154] 'read index received' (duration: 74.08876ms)","trace[1093641154] 'applied index is now lower than readState.Index' (duration: 27.492464ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:10:07.788452Z","caller":"traceutil/trace.go:171","msg":"trace[471119005] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"122.948191ms","start":"2026-07-01T10:10:07.665457Z","end":"2026-07-01T10:10:07.788405Z","steps":["trace[471119005] 'process raft request' (duration: 95.328259ms)","trace[471119005] 'compare' (duration: 27.442591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:10:07.788549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.761504ms","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-01T10:10:07.788653Z","caller":"traceutil/trace.go:171","msg":"trace[1655482926] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1355; }","duration":"101.907453ms","start":"2026-07-01T10:10:07.686723Z","end":"2026-07-01T10:10:07.788631Z","steps":["trace[1655482926] 'agreement among raft nodes before linearized reading' (duration: 101.720232ms)"],"step_count":1}