{"level":"warn","ts":"2026-04-07T00:01:39.35018Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:39.350275Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-04-07T00:01:39.350351Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:39.350368Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-07T00:01:39.350392Z","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-04-07T00:01:39.351188Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-07T00:01:39.351276Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-04-07T00:01:39.356003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.369824ms"} {"level":"info","ts":"2026-04-07T00:01:39.364162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-07T00:01:39.364237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:01:39.36426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:01:39.364273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:01:39.364305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:01:39.364342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-07T00:01:39.373289Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:01:39.374727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:01:39.376314Z","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-04-07T00:01:39.378186Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:01:39.378313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:01:39.378415Z","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-04-07T00:01:39.378479Z","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-04-07T00:01:39.378489Z","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-04-07T00:01:39.383388Z","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-04-07T00:01:39.383723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:01:39.383702Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-07T00:01:39.383756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:01:39.383824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-07T00:01:39.385866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-07T00:01:39.386008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-07T00:01:39.965394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:01:39.965463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:01:39.9655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-07T00:01:39.965516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:01:39.965524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-07T00:01:39.965562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:01:39.965588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-07T00:01:39.967846Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:01:39.968174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:39.968286Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:39.96848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:39.968662Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:01:39.968684Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:01:39.969177Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:39.969298Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:39.969332Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:39.971971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:01:39.973826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"warn","ts":"2026-04-07T00:01:51.34861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.014792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:01:51.348782Z","caller":"traceutil/trace.go:171","msg":"trace[2015111651] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"364.072111ms","start":"2026-04-07T00:01:50.984691Z","end":"2026-04-07T00:01:51.348764Z","steps":["trace[2015111651] 'process raft request' (duration: 166.323956ms)","trace[2015111651] 'compare' (duration: 196.879084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:51.348864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:50.984665Z","time spent":"364.15602ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:01:51.892864Z","caller":"traceutil/trace.go:171","msg":"trace[1471265150] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"116.046935ms","start":"2026-04-07T00:01:51.776779Z","end":"2026-04-07T00:01:51.892826Z","steps":["trace[1471265150] 'read index received' (duration: 115.712172ms)","trace[1471265150] 'applied index is now lower than readState.Index' (duration: 333.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:51.892938Z","caller":"traceutil/trace.go:171","msg":"trace[1503036651] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"121.586635ms","start":"2026-04-07T00:01:51.771319Z","end":"2026-04-07T00:01:51.892905Z","steps":["trace[1503036651] 'process raft request' (duration: 121.285722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.893073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.227621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:01:51.893127Z","caller":"traceutil/trace.go:171","msg":"trace[2005541181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:359; }","duration":"116.360679ms","start":"2026-04-07T00:01:51.776751Z","end":"2026-04-07T00:01:51.893111Z","steps":["trace[2005541181] 'agreement among raft nodes before linearized reading' (duration: 116.214602ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:01.602975Z","caller":"traceutil/trace.go:171","msg":"trace[2126393133] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"189.225813ms","start":"2026-04-07T00:02:01.413726Z","end":"2026-04-07T00:02:01.602951Z","steps":["trace[2126393133] 'read index received' (duration: 188.989726ms)","trace[2126393133] 'applied index is now lower than readState.Index' (duration: 234.827µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:01.603082Z","caller":"traceutil/trace.go:171","msg":"trace[1017817063] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"580.804922ms","start":"2026-04-07T00:02:01.022238Z","end":"2026-04-07T00:02:01.603043Z","steps":["trace[1017817063] 'process raft request' (duration: 580.568505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:01.603119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.364992ms","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-04-07T00:02:01.603152Z","caller":"traceutil/trace.go:171","msg":"trace[827615959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"189.45584ms","start":"2026-04-07T00:02:01.413686Z","end":"2026-04-07T00:02:01.603142Z","steps":["trace[827615959] 'agreement among raft nodes before linearized reading' (duration: 189.368811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:01.603228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:01.022221Z","time spent":"580.926921ms","remote":"127.0.0.1:57558","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-04-07T00:02:01.852748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.398467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:01.853551Z","caller":"traceutil/trace.go:171","msg":"trace[476267143] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"245.81293ms","start":"2026-04-07T00:02:01.607093Z","end":"2026-04-07T00:02:01.852906Z","steps":["trace[476267143] 'process raft request' (duration: 44.100067ms)","trace[476267143] 'compare' (duration: 201.270688ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:01.863482Z","caller":"traceutil/trace.go:171","msg":"trace[583699713] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"254.08018ms","start":"2026-04-07T00:02:01.609388Z","end":"2026-04-07T00:02:01.863468Z","steps":["trace[583699713] 'process raft request' (duration: 254.003071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:02.174856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.009781ms","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-04-07T00:02:02.174957Z","caller":"traceutil/trace.go:171","msg":"trace[301901744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"178.159929ms","start":"2026-04-07T00:02:01.996772Z","end":"2026-04-07T00:02:02.174932Z","steps":["trace[301901744] 'range keys from in-memory index tree' (duration: 177.897332ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:09.590337Z","caller":"traceutil/trace.go:171","msg":"trace[1535686575] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"109.605385ms","start":"2026-04-07T00:02:09.480703Z","end":"2026-04-07T00:02:09.590308Z","steps":["trace[1535686575] 'process raft request' (duration: 105.940814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:10.088535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.812476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:10.088869Z","caller":"traceutil/trace.go:171","msg":"trace[163951545] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"321.523298ms","start":"2026-04-07T00:02:09.767305Z","end":"2026-04-07T00:02:10.088828Z","steps":["trace[163951545] 'process raft request' (duration: 220.323184ms)","trace[163951545] 'compare' (duration: 100.660086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:10.089085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:09.767279Z","time spent":"321.671327ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:10.08934Z","caller":"traceutil/trace.go:171","msg":"trace[2098913504] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"312.536037ms","start":"2026-04-07T00:02:09.776786Z","end":"2026-04-07T00:02:10.089322Z","steps":["trace[2098913504] 'process raft request' (duration: 312.00355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:10.089433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:09.776764Z","time spent":"312.627697ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:10.092602Z","caller":"traceutil/trace.go:171","msg":"trace[551727220] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:532; }","duration":"148.072303ms","start":"2026-04-07T00:02:09.944509Z","end":"2026-04-07T00:02:10.092581Z","steps":["trace[551727220] 'read index received' (duration: 43.211437ms)","trace[551727220] 'applied index is now lower than readState.Index' (duration: 104.859696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:10.092815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.223532ms","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-04-07T00:02:10.09286Z","caller":"traceutil/trace.go:171","msg":"trace[791523438] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"230.222336ms","start":"2026-04-07T00:02:09.862616Z","end":"2026-04-07T00:02:10.092839Z","steps":["trace[791523438] 'process raft request' (duration: 229.847698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:10.092879Z","caller":"traceutil/trace.go:171","msg":"trace[874019912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"148.411171ms","start":"2026-04-07T00:02:09.944458Z","end":"2026-04-07T00:02:10.092869Z","steps":["trace[874019912] 'agreement among raft nodes before linearized reading' (duration: 148.201592ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:10.469497Z","caller":"traceutil/trace.go:171","msg":"trace[1036213434] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"333.258939ms","start":"2026-04-07T00:02:10.136204Z","end":"2026-04-07T00:02:10.469463Z","steps":["trace[1036213434] 'process raft request' (duration: 285.69655ms)","trace[1036213434] 'compare' (duration: 47.3331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:10.469658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.136177Z","time spent":"333.397288ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:10.808121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.287109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:10.808449Z","caller":"traceutil/trace.go:171","msg":"trace[279557239] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"635.178223ms","start":"2026-04-07T00:02:10.173232Z","end":"2026-04-07T00:02:10.80841Z","steps":["trace[279557239] 'process raft request' (duration: 458.529815ms)","trace[279557239] 'compare' (duration: 175.960491ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:10.808476Z","caller":"traceutil/trace.go:171","msg":"trace[140479507] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:536; }","duration":"567.22886ms","start":"2026-04-07T00:02:10.24123Z","end":"2026-04-07T00:02:10.808459Z","steps":["trace[140479507] 'read index received' (duration: 180.683849ms)","trace[140479507] 'applied index is now lower than readState.Index' (duration: 386.544061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:10.80855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.173199Z","time spent":"635.301193ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:02:10.808565Z","caller":"traceutil/trace.go:171","msg":"trace[1770322777] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"624.105306ms","start":"2026-04-07T00:02:10.18445Z","end":"2026-04-07T00:02:10.808555Z","steps":["trace[1770322777] 'process raft request' (duration: 623.806297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:10.808644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.184433Z","time spent":"624.160126ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:10.808668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.130035ms","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-04-07T00:02:10.808697Z","caller":"traceutil/trace.go:171","msg":"trace[975084113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"566.200185ms","start":"2026-04-07T00:02:10.242488Z","end":"2026-04-07T00:02:10.808688Z","steps":["trace[975084113] 'agreement among raft nodes before linearized reading' (duration: 566.138225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:10.808719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.242467Z","time spent":"566.247615ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:02:10.808742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.491329ms","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-04-07T00:02:10.808819Z","caller":"traceutil/trace.go:171","msg":"trace[137775187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"567.615038ms","start":"2026-04-07T00:02:10.241183Z","end":"2026-04-07T00:02:10.808798Z","steps":["trace[137775187] 'agreement among raft nodes before linearized reading' (duration: 567.488819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:10.808858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.24116Z","time spent":"567.690048ms","remote":"127.0.0.1:57558","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-04-07T00:02:11.362401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.469166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:11.362792Z","caller":"traceutil/trace.go:171","msg":"trace[916744272] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"547.083784ms","start":"2026-04-07T00:02:10.815669Z","end":"2026-04-07T00:02:11.362752Z","steps":["trace[916744272] 'process raft request' (duration: 412.17046ms)","trace[916744272] 'compare' (duration: 134.226407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:11.362998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.815635Z","time spent":"547.224845ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:11.363001Z","caller":"traceutil/trace.go:171","msg":"trace[266911417] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"533.651448ms","start":"2026-04-07T00:02:10.829319Z","end":"2026-04-07T00:02:11.362971Z","steps":["trace[266911417] 'process raft request' (duration: 533.181189ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:11.363013Z","caller":"traceutil/trace.go:171","msg":"trace[1775684868] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"532.600943ms","start":"2026-04-07T00:02:10.830372Z","end":"2026-04-07T00:02:11.362973Z","steps":["trace[1775684868] 'process raft request' (duration: 532.400893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:11.363084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.829307Z","time spent":"533.743408ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:02:11.363321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.830344Z","time spent":"532.767273ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:11.363576Z","caller":"traceutil/trace.go:171","msg":"trace[1733463457] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"489.064386ms","start":"2026-04-07T00:02:10.874499Z","end":"2026-04-07T00:02:11.363563Z","steps":["trace[1733463457] 'process raft request' (duration: 488.770627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:11.363786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:10.874398Z","time spent":"489.250054ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:11.696122Z","caller":"traceutil/trace.go:171","msg":"trace[975445591] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"134.048727ms","start":"2026-04-07T00:02:11.562042Z","end":"2026-04-07T00:02:11.696091Z","steps":["trace[975445591] 'process raft request' (duration: 87.932587ms)","trace[975445591] 'compare' (duration: 45.996931ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:12.011513Z","caller":"traceutil/trace.go:171","msg":"trace[1280171899] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"120.030396ms","start":"2026-04-07T00:02:11.891445Z","end":"2026-04-07T00:02:12.011475Z","steps":["trace[1280171899] 'process raft request' (duration: 107.159023ms)","trace[1280171899] 'compare' (duration: 12.563684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:12.603429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:13.103939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:13.38661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.451889ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:13.386727Z","caller":"traceutil/trace.go:171","msg":"trace[899339666] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:544; }","duration":"191.581908ms","start":"2026-04-07T00:02:13.195128Z","end":"2026-04-07T00:02:13.38671Z","steps":["trace[899339666] 'range keys from in-memory index tree' (duration: 191.439619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.387233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297953098s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:02:13.387567Z","caller":"traceutil/trace.go:171","msg":"trace[1252740943] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.298321887s","start":"2026-04-07T00:02:12.089199Z","end":"2026-04-07T00:02:13.38752Z","steps":["trace[1252740943] 'process raft request' (duration: 1.298174877s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.387685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.089182Z","time spent":"1.298434467s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:13.466739Z","caller":"traceutil/trace.go:171","msg":"trace[428017577] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"1.36387542s","start":"2026-04-07T00:02:12.102838Z","end":"2026-04-07T00:02:13.466714Z","steps":["trace[428017577] 'read index received' (duration: 1.2847431s)","trace[428017577] 'applied index is now lower than readState.Index' (duration: 79.13149ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:13.466922Z","caller":"traceutil/trace.go:171","msg":"trace[33980509] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.259953227s","start":"2026-04-07T00:02:12.206883Z","end":"2026-04-07T00:02:13.466836Z","steps":["trace[33980509] 'process raft request' (duration: 1.258671449s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.466992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364126899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-07T00:02:13.46704Z","caller":"traceutil/trace.go:171","msg":"trace[1817591937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"1.364222028s","start":"2026-04-07T00:02:12.102803Z","end":"2026-04-07T00:02:13.467025Z","steps":["trace[1817591937] 'agreement among raft nodes before linearized reading' (duration: 1.364036699s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.467083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.102788Z","time spent":"1.364286168s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-07T00:02:13.46723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.206853Z","time spent":"1.260146405s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:13.534373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.279331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:02:13.534425Z","caller":"traceutil/trace.go:171","msg":"trace[1061216442] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:548; }","duration":"427.392571ms","start":"2026-04-07T00:02:13.10702Z","end":"2026-04-07T00:02:13.534413Z","steps":["trace[1061216442] 'agreement among raft nodes before linearized reading' (duration: 427.273341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.534463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:13.107001Z","time spent":"427.456501ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-04-07T00:02:13.534635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.388242ms","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-04-07T00:02:13.534642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.290513ms","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-04-07T00:02:13.534712Z","caller":"traceutil/trace.go:171","msg":"trace[736853031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"631.414683ms","start":"2026-04-07T00:02:12.90328Z","end":"2026-04-07T00:02:13.534695Z","steps":["trace[736853031] 'agreement among raft nodes before linearized reading' (duration: 631.249754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.534742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.90326Z","time spent":"631.477343ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:02:13.534795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.063146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:13.534817Z","caller":"traceutil/trace.go:171","msg":"trace[1263480333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"974.122896ms","start":"2026-04-07T00:02:12.560689Z","end":"2026-04-07T00:02:13.534812Z","steps":["trace[1263480333] 'agreement among raft nodes before linearized reading' (duration: 974.089946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.534851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.560669Z","time spent":"974.178106ms","remote":"127.0.0.1:58020","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-04-07T00:02:13.534656Z","caller":"traceutil/trace.go:171","msg":"trace[1190497344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"165.449432ms","start":"2026-04-07T00:02:13.369201Z","end":"2026-04-07T00:02:13.53465Z","steps":["trace[1190497344] 'agreement among raft nodes before linearized reading' (duration: 165.411172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.535122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385945411s","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-04-07T00:02:13.535156Z","caller":"traceutil/trace.go:171","msg":"trace[1988941202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"1.38603424s","start":"2026-04-07T00:02:12.149117Z","end":"2026-04-07T00:02:13.535151Z","steps":["trace[1988941202] 'agreement among raft nodes before linearized reading' (duration: 1.3859518s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:13.535172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:12.149096Z","time spent":"1.38607233s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:02:16.374414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.208079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:16.374557Z","caller":"traceutil/trace.go:171","msg":"trace[1331849294] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"212.781875ms","start":"2026-04-07T00:02:16.161751Z","end":"2026-04-07T00:02:16.374532Z","steps":["trace[1331849294] 'process raft request' (duration: 93.118605ms)","trace[1331849294] 'compare' (duration: 118.698439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:17.823188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.899072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:17.823274Z","caller":"traceutil/trace.go:171","msg":"trace[863234786] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"224.317996ms","start":"2026-04-07T00:02:17.598941Z","end":"2026-04-07T00:02:17.823259Z","steps":["trace[863234786] 'read index received' (duration: 37.49µs)","trace[863234786] 'applied index is now lower than readState.Index' (duration: 224.279446ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:17.823317Z","caller":"traceutil/trace.go:171","msg":"trace[268175524] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"883.211497ms","start":"2026-04-07T00:02:16.940072Z","end":"2026-04-07T00:02:17.823284Z","steps":["trace[268175524] 'process raft request' (duration: 166.154305ms)","trace[268175524] 'compare' (duration: 716.583852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:17.823483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:16.94005Z","time spent":"883.366467ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:02:17.823513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.404986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:02:17.823616Z","caller":"traceutil/trace.go:171","msg":"trace[806642645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"224.552966ms","start":"2026-04-07T00:02:17.599044Z","end":"2026-04-07T00:02:17.823597Z","steps":["trace[806642645] 'agreement among raft nodes before linearized reading' (duration: 224.315476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:17.823617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.663356ms","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-04-07T00:02:17.823674Z","caller":"traceutil/trace.go:171","msg":"trace[1985366021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"224.752766ms","start":"2026-04-07T00:02:17.598906Z","end":"2026-04-07T00:02:17.823659Z","steps":["trace[1985366021] 'agreement among raft nodes before linearized reading' (duration: 224.434386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:17.82379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.672016ms","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-04-07T00:02:17.823831Z","caller":"traceutil/trace.go:171","msg":"trace[41644359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"223.727916ms","start":"2026-04-07T00:02:17.60009Z","end":"2026-04-07T00:02:17.823818Z","steps":["trace[41644359] 'agreement among raft nodes before linearized reading' (duration: 223.316886ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:18.440256Z","caller":"traceutil/trace.go:171","msg":"trace[408886265] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"212.378558ms","start":"2026-04-07T00:02:18.227857Z","end":"2026-04-07T00:02:18.440236Z","steps":["trace[408886265] 'read index received' (duration: 211.816808ms)","trace[408886265] 'applied index is now lower than readState.Index' (duration: 560.93µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:18.440345Z","caller":"traceutil/trace.go:171","msg":"trace[93651827] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"214.34696ms","start":"2026-04-07T00:02:18.22599Z","end":"2026-04-07T00:02:18.440337Z","steps":["trace[93651827] 'process raft request' (duration: 213.80485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.44062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.724198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.44072Z","caller":"traceutil/trace.go:171","msg":"trace[159329080] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:573; }","duration":"212.861198ms","start":"2026-04-07T00:02:18.227829Z","end":"2026-04-07T00:02:18.44069Z","steps":["trace[159329080] 'agreement among raft nodes before linearized reading' (duration: 212.675678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.604837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.298801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:02:18.605074Z","caller":"traceutil/trace.go:171","msg":"trace[1375637389] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"104.077617ms","start":"2026-04-07T00:02:18.500979Z","end":"2026-04-07T00:02:18.605057Z","steps":["trace[1375637389] 'process raft request' (duration: 103.972267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.605276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.359052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.605087Z","caller":"traceutil/trace.go:171","msg":"trace[1004128] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"164.721935ms","start":"2026-04-07T00:02:18.440312Z","end":"2026-04-07T00:02:18.605034Z","steps":["trace[1004128] 'read index received' (duration: 38.154453ms)","trace[1004128] 'applied index is now lower than readState.Index' (duration: 126.565172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:18.605283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.398002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.605456Z","caller":"traceutil/trace.go:171","msg":"trace[1555189776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:574; }","duration":"198.601502ms","start":"2026-04-07T00:02:18.406823Z","end":"2026-04-07T00:02:18.605425Z","steps":["trace[1555189776] 'agreement among raft nodes before linearized reading' (duration: 198.403062ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:18.60534Z","caller":"traceutil/trace.go:171","msg":"trace[1150295317] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:574; }","duration":"198.473552ms","start":"2026-04-07T00:02:18.406853Z","end":"2026-04-07T00:02:18.605326Z","steps":["trace[1150295317] 'agreement among raft nodes before linearized reading' (duration: 198.276372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.817049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.052631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.817157Z","caller":"traceutil/trace.go:171","msg":"trace[1316977942] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:574; }","duration":"136.191962ms","start":"2026-04-07T00:02:18.680954Z","end":"2026-04-07T00:02:18.817146Z","steps":["trace[1316977942] 'range keys from in-memory index tree' (duration: 136.008361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.817259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.366353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.817295Z","caller":"traceutil/trace.go:171","msg":"trace[1205343372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:574; }","duration":"136.443893ms","start":"2026-04-07T00:02:18.680845Z","end":"2026-04-07T00:02:18.817289Z","steps":["trace[1205343372] 'count revisions from in-memory index tree' (duration: 136.219062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:18.817379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.481083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:18.817414Z","caller":"traceutil/trace.go:171","msg":"trace[2081602498] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:574; }","duration":"135.538713ms","start":"2026-04-07T00:02:18.681871Z","end":"2026-04-07T00:02:18.817409Z","steps":["trace[2081602498] 'range keys from in-memory index tree' (duration: 135.436963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:19.343629Z","caller":"traceutil/trace.go:171","msg":"trace[1997170999] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"189.99694ms","start":"2026-04-07T00:02:19.153605Z","end":"2026-04-07T00:02:19.343601Z","steps":["trace[1997170999] 'read index received' (duration: 189.755029ms)","trace[1997170999] 'applied index is now lower than readState.Index' (duration: 240.731µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:19.343912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.277041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.34404Z","caller":"traceutil/trace.go:171","msg":"trace[645260401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"190.391211ms","start":"2026-04-07T00:02:19.153601Z","end":"2026-04-07T00:02:19.343992Z","steps":["trace[645260401] 'agreement among raft nodes before linearized reading' (duration: 190.182821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:19.344014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.337191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.343992Z","caller":"traceutil/trace.go:171","msg":"trace[510588646] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"205.255038ms","start":"2026-04-07T00:02:19.13871Z","end":"2026-04-07T00:02:19.343965Z","steps":["trace[510588646] 'process raft request' (duration: 204.721466ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:19.34416Z","caller":"traceutil/trace.go:171","msg":"trace[433542868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"190.514031ms","start":"2026-04-07T00:02:19.153629Z","end":"2026-04-07T00:02:19.344143Z","steps":["trace[433542868] 'agreement among raft nodes before linearized reading' (duration: 190.312741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:19.344866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.37619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.344933Z","caller":"traceutil/trace.go:171","msg":"trace[267584460] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"190.45941ms","start":"2026-04-07T00:02:19.15446Z","end":"2026-04-07T00:02:19.34492Z","steps":["trace[267584460] 'agreement among raft nodes before linearized reading' (duration: 190.345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:19.345055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.410731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.345117Z","caller":"traceutil/trace.go:171","msg":"trace[1192578521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"100.507701ms","start":"2026-04-07T00:02:19.244588Z","end":"2026-04-07T00:02:19.345096Z","steps":["trace[1192578521] 'agreement among raft nodes before linearized reading' (duration: 100.430901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:19.34512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.26461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.345157Z","caller":"traceutil/trace.go:171","msg":"trace[560097337] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"100.31553ms","start":"2026-04-07T00:02:19.244833Z","end":"2026-04-07T00:02:19.345149Z","steps":["trace[560097337] 'agreement among raft nodes before linearized reading' (duration: 100.26269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:19.346494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.2202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:19.346587Z","caller":"traceutil/trace.go:171","msg":"trace[70891770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"101.770582ms","start":"2026-04-07T00:02:19.244793Z","end":"2026-04-07T00:02:19.346564Z","steps":["trace[70891770] 'agreement among raft nodes before linearized reading' (duration: 100.216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:22.653218Z","caller":"traceutil/trace.go:171","msg":"trace[2021681721] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"574.263023ms","start":"2026-04-07T00:02:22.078934Z","end":"2026-04-07T00:02:22.653197Z","steps":["trace[2021681721] 'process raft request' (duration: 574.130103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:22.653341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:22.078901Z","time spent":"574.377853ms","remote":"127.0.0.1:57462","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-04-07T00:02:22.880308Z","caller":"traceutil/trace.go:171","msg":"trace[1022129704] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"319.292156ms","start":"2026-04-07T00:02:22.560986Z","end":"2026-04-07T00:02:22.880278Z","steps":["trace[1022129704] 'read index received' (duration: 92.018775ms)","trace[1022129704] 'applied index is now lower than readState.Index' (duration: 227.271911ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:22.880412Z","caller":"traceutil/trace.go:171","msg":"trace[1338356839] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"787.46182ms","start":"2026-04-07T00:02:22.09292Z","end":"2026-04-07T00:02:22.880382Z","steps":["trace[1338356839] 'process raft request' (duration: 778.773627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:22.880481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.473596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:22.880556Z","caller":"traceutil/trace.go:171","msg":"trace[519804155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"319.610656ms","start":"2026-04-07T00:02:22.560933Z","end":"2026-04-07T00:02:22.880544Z","steps":["trace[519804155] 'agreement among raft nodes before linearized reading' (duration: 319.478576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:22.880603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:22.560913Z","time spent":"319.679427ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:02:22.880749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:22.092906Z","time spent":"787.675641ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:23.382288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.264849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:02:23.382382Z","caller":"traceutil/trace.go:171","msg":"trace[1388445001] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:600; }","duration":"274.440199ms","start":"2026-04-07T00:02:23.107915Z","end":"2026-04-07T00:02:23.382356Z","steps":["trace[1388445001] 'range keys from in-memory index tree' (duration: 274.007947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:23.382355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.75885ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:23.382536Z","caller":"traceutil/trace.go:171","msg":"trace[215548626] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:600; }","duration":"187.952681ms","start":"2026-04-07T00:02:23.194552Z","end":"2026-04-07T00:02:23.382504Z","steps":["trace[215548626] 'range keys from in-memory index tree' (duration: 187.71283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:24.362312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:24.863078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:25.363649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:25.864042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:26.350249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99982282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T00:02:26.350332Z","caller":"traceutil/trace.go:171","msg":"trace[241680299] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999965601s","start":"2026-04-07T00:02:24.350348Z","end":"2026-04-07T00:02:26.350313Z","steps":["trace[241680299] 'agreement among raft nodes before linearized reading' (duration: 1.99985834s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.350382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:24.350329Z","time spent":"2.000043151s","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 00:02:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T00:02:26.364708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:26.56063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000181298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T00:02:26.560707Z","caller":"traceutil/trace.go:171","msg":"trace[297933170] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000329228s","start":"2026-04-07T00:02:24.560356Z","end":"2026-04-07T00:02:26.560686Z","steps":["trace[297933170] 'agreement among raft nodes before linearized reading' (duration: 2.000237468s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.560755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:24.560326Z","time spent":"2.000416068s","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 00:02:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T00:02:26.579733Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.720695909s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:02:26.580073Z","caller":"traceutil/trace.go:171","msg":"trace[785336480] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"2.721119912s","start":"2026-04-07T00:02:23.858937Z","end":"2026-04-07T00:02:26.580057Z","steps":["trace[785336480] 'process raft request' (duration: 2.720981321s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.580186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:23.858921Z","time spent":"2.721201242s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:26.673861Z","caller":"traceutil/trace.go:171","msg":"trace[855015064] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"710.418714ms","start":"2026-04-07T00:02:25.963421Z","end":"2026-04-07T00:02:26.67384Z","steps":["trace[855015064] 'process raft request' (duration: 708.234421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.673991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:25.963393Z","time spent":"710.527565ms","remote":"127.0.0.1:57558","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-04-07T00:02:26.674109Z","caller":"traceutil/trace.go:171","msg":"trace[344751218] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:619; }","duration":"2.812557384s","start":"2026-04-07T00:02:23.861511Z","end":"2026-04-07T00:02:26.674069Z","steps":["trace[344751218] 'read index received' (duration: 2.7184042s)","trace[344751218] 'applied index is now lower than readState.Index' (duration: 94.150634ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:26.674115Z","caller":"traceutil/trace.go:171","msg":"trace[922786660] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"320.41291ms","start":"2026-04-07T00:02:26.353669Z","end":"2026-04-07T00:02:26.674082Z","steps":["trace[922786660] 'process raft request' (duration: 320.112908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.674355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.826543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:02:26.674373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:26.353643Z","time spent":"320.633051ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:02:26.6744Z","caller":"traceutil/trace.go:171","msg":"trace[1109975955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"106.923024ms","start":"2026-04-07T00:02:26.567465Z","end":"2026-04-07T00:02:26.674388Z","steps":["trace[1109975955] 'agreement among raft nodes before linearized reading' (duration: 106.830113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.674406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.80930646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:02:26.674459Z","caller":"traceutil/trace.go:171","msg":"trace[1909259603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.8094048s","start":"2026-04-07T00:02:23.865041Z","end":"2026-04-07T00:02:26.674445Z","steps":["trace[1909259603] 'agreement among raft nodes before linearized reading' (duration: 2.809248989s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.674436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.805438603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T00:02:26.67449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:23.86502Z","time spent":"2.809463251s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:02:26.674527Z","caller":"traceutil/trace.go:171","msg":"trace[576182188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.805555453s","start":"2026-04-07T00:02:23.86895Z","end":"2026-04-07T00:02:26.674506Z","steps":["trace[576182188] 'agreement among raft nodes before linearized reading' (duration: 2.805364592s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.674573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:23.868943Z","time spent":"2.805621374s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:02:26.674436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.812899116s","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-04-07T00:02:26.674634Z","caller":"traceutil/trace.go:171","msg":"trace[1569272325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"2.813130267s","start":"2026-04-07T00:02:23.861492Z","end":"2026-04-07T00:02:26.674623Z","steps":["trace[1569272325] 'agreement among raft nodes before linearized reading' (duration: 2.812678384s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:26.674665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:23.86148Z","time spent":"2.813175337s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T00:02:29.421997Z","caller":"traceutil/trace.go:171","msg":"trace[796779998] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"108.686425ms","start":"2026-04-07T00:02:29.313289Z","end":"2026-04-07T00:02:29.421976Z","steps":["trace[796779998] 'process raft request' (duration: 108.579594ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:31.670949Z","caller":"traceutil/trace.go:171","msg":"trace[1419452951] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"110.521503ms","start":"2026-04-07T00:02:31.560406Z","end":"2026-04-07T00:02:31.670927Z","steps":["trace[1419452951] 'read index received' (duration: 110.347492ms)","trace[1419452951] 'applied index is now lower than readState.Index' (duration: 173.141µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:31.671041Z","caller":"traceutil/trace.go:171","msg":"trace[1422629228] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"155.990607ms","start":"2026-04-07T00:02:31.515027Z","end":"2026-04-07T00:02:31.671018Z","steps":["trace[1422629228] 'process raft request' (duration: 155.798325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:31.671125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.676755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:31.671296Z","caller":"traceutil/trace.go:171","msg":"trace[1900083040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"110.874726ms","start":"2026-04-07T00:02:31.560373Z","end":"2026-04-07T00:02:31.671248Z","steps":["trace[1900083040] 'agreement among raft nodes before linearized reading' (duration: 110.655764ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:41.399339Z","caller":"traceutil/trace.go:171","msg":"trace[1259661513] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"135.672875ms","start":"2026-04-07T00:02:41.263644Z","end":"2026-04-07T00:02:41.399317Z","steps":["trace[1259661513] 'process raft request' (duration: 135.531333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:43.402951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830975986149968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:02:43.790193Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018404843s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T00:02:43.996747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.426036ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:43.996877Z","caller":"traceutil/trace.go:171","msg":"trace[395397600] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:760; }","duration":"802.567658ms","start":"2026-04-07T00:02:43.194289Z","end":"2026-04-07T00:02:43.996856Z","steps":["trace[395397600] 'range keys from in-memory index tree' (duration: 802.409246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:43.997049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.523772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:43.997147Z","caller":"traceutil/trace.go:171","msg":"trace[1023667625] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.225431292s","start":"2026-04-07T00:02:42.771699Z","end":"2026-04-07T00:02:43.997131Z","steps":["trace[1023667625] 'process raft request' (duration: 1.018749699s)","trace[1023667625] 'compare' (duration: 206.252787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:43.997208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:42.771683Z","time spent":"1.225494793s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:02:44.00398Z","caller":"traceutil/trace.go:171","msg":"trace[2061079697] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:782; }","duration":"1.101701124s","start":"2026-04-07T00:02:42.902261Z","end":"2026-04-07T00:02:44.003962Z","steps":["trace[2061079697] 'read index received' (duration: 888.199586ms)","trace[2061079697] 'applied index is now lower than readState.Index' (duration: 213.500867ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:44.004138Z","caller":"traceutil/trace.go:171","msg":"trace[1572525664] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"815.776359ms","start":"2026-04-07T00:02:43.188327Z","end":"2026-04-07T00:02:44.004104Z","steps":["trace[1572525664] 'process raft request' (duration: 815.498493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.004261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.188307Z","time spent":"815.88963ms","remote":"127.0.0.1:57524","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-04-07T00:02:44.00432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.569496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:44.00439Z","caller":"traceutil/trace.go:171","msg":"trace[1385189056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"443.667117ms","start":"2026-04-07T00:02:43.560706Z","end":"2026-04-07T00:02:44.004373Z","steps":["trace[1385189056] 'agreement among raft nodes before linearized reading' (duration: 443.541135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.004431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.560689Z","time spent":"443.735158ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:02:44.004491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.087594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:02:44.004548Z","caller":"traceutil/trace.go:171","msg":"trace[2138705103] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:763; }","duration":"493.201865ms","start":"2026-04-07T00:02:43.511335Z","end":"2026-04-07T00:02:44.004537Z","steps":["trace[2138705103] 'agreement among raft nodes before linearized reading' (duration: 493.024203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.004453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102181771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T00:02:44.004584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.37006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-07T00:02:44.004333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.172459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:02:44.004611Z","caller":"traceutil/trace.go:171","msg":"trace[110953606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"1.102372543s","start":"2026-04-07T00:02:42.90223Z","end":"2026-04-07T00:02:44.004602Z","steps":["trace[110953606] 'agreement among raft nodes before linearized reading' (duration: 1.101889076s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.004751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:42.902219Z","time spent":"1.102523206s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T00:02:44.004614Z","caller":"traceutil/trace.go:171","msg":"trace[573271444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"601.470981ms","start":"2026-04-07T00:02:43.403134Z","end":"2026-04-07T00:02:44.004605Z","steps":["trace[573271444] 'agreement among raft nodes before linearized reading' (duration: 601.3853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:44.004629Z","caller":"traceutil/trace.go:171","msg":"trace[1621820404] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:763; }","duration":"895.505974ms","start":"2026-04-07T00:02:43.109113Z","end":"2026-04-07T00:02:44.004618Z","steps":["trace[1621820404] 'agreement among raft nodes before linearized reading' (duration: 895.008596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.004909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.403118Z","time spent":"601.716755ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:02:44.004993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.109094Z","time spent":"895.870369ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-04-07T00:02:44.00421Z","caller":"traceutil/trace.go:171","msg":"trace[1952251706] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"612.276165ms","start":"2026-04-07T00:02:43.391895Z","end":"2026-04-07T00:02:44.004171Z","steps":["trace[1952251706] 'process raft request' (duration: 612.036401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.00508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.391879Z","time spent":"613.170738ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:44.004523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102302953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:02:44.005212Z","caller":"traceutil/trace.go:171","msg":"trace[1108205305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"1.102982313s","start":"2026-04-07T00:02:42.902147Z","end":"2026-04-07T00:02:44.005161Z","steps":["trace[1108205305] 'agreement among raft nodes before linearized reading' (duration: 1.102187481s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:44.005268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:42.902127Z","time spent":"1.103126285s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:02:44.00463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:43.51132Z","time spent":"493.272626ms","remote":"127.0.0.1:57774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:02:47.334264Z","caller":"traceutil/trace.go:171","msg":"trace[581372589] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"126.062768ms","start":"2026-04-07T00:02:47.208183Z","end":"2026-04-07T00:02:47.334246Z","steps":["trace[581372589] 'process raft request' (duration: 125.939006ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:47.449266Z","caller":"traceutil/trace.go:171","msg":"trace[1529958901] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"108.866808ms","start":"2026-04-07T00:02:47.340355Z","end":"2026-04-07T00:02:47.449222Z","steps":["trace[1529958901] 'process raft request' (duration: 108.669905ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:47.559901Z","caller":"traceutil/trace.go:171","msg":"trace[796901956] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"102.347639ms","start":"2026-04-07T00:02:47.457517Z","end":"2026-04-07T00:02:47.559865Z","steps":["trace[796901956] 'process raft request' (duration: 99.131525ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:58.175412Z","caller":"traceutil/trace.go:171","msg":"trace[906792240] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"106.471109ms","start":"2026-04-07T00:02:58.068923Z","end":"2026-04-07T00:02:58.175394Z","steps":["trace[906792240] 'read index received' (duration: 106.291515ms)","trace[906792240] 'applied index is now lower than readState.Index' (duration: 178.544µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:58.175795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.857297ms","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-04-07T00:02:58.17623Z","caller":"traceutil/trace.go:171","msg":"trace[642942750] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"107.49328ms","start":"2026-04-07T00:02:58.06825Z","end":"2026-04-07T00:02:58.175743Z","steps":["trace[642942750] 'process raft request' (duration: 107.032551ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:58.176387Z","caller":"traceutil/trace.go:171","msg":"trace[894252108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"106.964659ms","start":"2026-04-07T00:02:58.068896Z","end":"2026-04-07T00:02:58.175861Z","steps":["trace[894252108] 'agreement among raft nodes before linearized reading' (duration: 106.741724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:29.232525Z","caller":"traceutil/trace.go:171","msg":"trace[798035215] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"176.255139ms","start":"2026-04-07T00:03:29.056245Z","end":"2026-04-07T00:03:29.2325Z","steps":["trace[798035215] 'read index received' (duration: 176.103275ms)","trace[798035215] 'applied index is now lower than readState.Index' (duration: 150.934µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:03:29.232673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.403843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:03:29.23271Z","caller":"traceutil/trace.go:171","msg":"trace[709228203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"176.476295ms","start":"2026-04-07T00:03:29.056225Z","end":"2026-04-07T00:03:29.232701Z","steps":["trace[709228203] 'agreement among raft nodes before linearized reading' (duration: 176.381372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:29.23278Z","caller":"traceutil/trace.go:171","msg":"trace[1565146935] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"215.54274ms","start":"2026-04-07T00:03:29.01721Z","end":"2026-04-07T00:03:29.232753Z","steps":["trace[1565146935] 'process raft request' (duration: 215.135078ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:29.520981Z","caller":"traceutil/trace.go:171","msg":"trace[1605278109] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"377.733857ms","start":"2026-04-07T00:03:29.14323Z","end":"2026-04-07T00:03:29.520964Z","steps":["trace[1605278109] 'process raft request' (duration: 377.612743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:29.521096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:03:29.143206Z","time spent":"377.829449ms","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:04:28.854435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.738274ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:04:35.02877Z","caller":"traceutil/trace.go:171","msg":"trace[1640874945] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"123.858701ms","start":"2026-04-07T00:04:34.904886Z","end":"2026-04-07T00:04:35.028745Z","steps":["trace[1640874945] 'read index received' (duration: 123.663023ms)","trace[1640874945] 'applied index is now lower than readState.Index' (duration: 194.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:35.028878Z","caller":"traceutil/trace.go:171","msg":"trace[1119971817] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"125.250836ms","start":"2026-04-07T00:04:34.903593Z","end":"2026-04-07T00:04:35.028844Z","steps":["trace[1119971817] 'process raft request' (duration: 124.979994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:35.029409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.470666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T00:04:35.02942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.427812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:04:35.029485Z","caller":"traceutil/trace.go:171","msg":"trace[1090089551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"124.58036ms","start":"2026-04-07T00:04:34.904885Z","end":"2026-04-07T00:04:35.029465Z","steps":["trace[1090089551] 'agreement among raft nodes before linearized reading' (duration: 124.093121ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:35.029509Z","caller":"traceutil/trace.go:171","msg":"trace[1104102349] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1298; }","duration":"105.62752ms","start":"2026-04-07T00:04:34.923863Z","end":"2026-04-07T00:04:35.02949Z","steps":["trace[1104102349] 'agreement among raft nodes before linearized reading' (duration: 105.191903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:35.029523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.328871ms","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-04-07T00:04:35.029715Z","caller":"traceutil/trace.go:171","msg":"trace[1839237288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"124.57995ms","start":"2026-04-07T00:04:34.905115Z","end":"2026-04-07T00:04:35.029695Z","steps":["trace[1839237288] 'agreement among raft nodes before linearized reading' (duration: 124.187444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:35.029713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.805528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:04:35.030272Z","caller":"traceutil/trace.go:171","msg":"trace[1543327178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"125.390211ms","start":"2026-04-07T00:04:34.904862Z","end":"2026-04-07T00:04:35.030252Z","steps":["trace[1543327178] 'agreement among raft nodes before linearized reading' (duration: 124.044339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:37.326287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.484306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:04:37.326421Z","caller":"traceutil/trace.go:171","msg":"trace[1568044942] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"273.26614ms","start":"2026-04-07T00:04:37.053142Z","end":"2026-04-07T00:04:37.326408Z","steps":["trace[1568044942] 'process raft request' (duration: 273.215328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:37.326484Z","caller":"traceutil/trace.go:171","msg":"trace[1733593185] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"273.732717ms","start":"2026-04-07T00:04:37.05272Z","end":"2026-04-07T00:04:37.326452Z","steps":["trace[1733593185] 'process raft request' (duration: 25.710409ms)","trace[1733593185] 'compare' (duration: 247.394293ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:38.120405Z","caller":"traceutil/trace.go:171","msg":"trace[663399669] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"236.895349ms","start":"2026-04-07T00:04:37.883485Z","end":"2026-04-07T00:04:38.120381Z","steps":["trace[663399669] 'process raft request' (duration: 236.727632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:04.33319Z","caller":"traceutil/trace.go:171","msg":"trace[1333760012] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"115.19851ms","start":"2026-04-07T00:05:04.217966Z","end":"2026-04-07T00:05:04.333164Z","steps":["trace[1333760012] 'process raft request' (duration: 115.021473ms)"],"step_count":1}