{"level":"warn","ts":"2026-08-12T11:03:58.157866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:03:58.157947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.102:2380","--initial-cluster=instance=https://199.19.213.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.102: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-08-12T11:03:58.158013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:03:58.158027Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-08-12T11:03:58.158044Z","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-08-12T11:03:58.158652Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"]} {"level":"info","ts":"2026-08-12T11:03:58.158728Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.102: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-08-12T11:03:58.161251Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.335125ms"} {"level":"info","ts":"2026-08-12T11:03:58.166819Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee87564e61d159f","cluster-id":"92a92aec402e97c3"} {"level":"info","ts":"2026-08-12T11:03:58.166895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:03:58.166917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 0"} {"level":"info","ts":"2026-08-12T11:03:58.166929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee87564e61d159f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:03:58.166938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 1"} {"level":"info","ts":"2026-08-12T11:03:58.166975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"warn","ts":"2026-08-12T11:03:58.171827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:03:58.173726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:03:58.174771Z","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-08-12T11:03:58.177882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee87564e61d159f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:03:58.178117Z","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-08-12T11:03:58.178792Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee87564e61d159f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:03:58.178964Z","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-08-12T11:03:58.179297Z","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-08-12T11:03:58.179715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"info","ts":"2026-08-12T11:03:58.179875Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","added-peer-id":"1ee87564e61d159f","added-peer-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-08-12T11:03:58.1819Z","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-08-12T11:03:58.182124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee87564e61d159f","initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:03:58.182159Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:03:58.182229Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-08-12T11:03:58.182243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-08-12T11:03:59.067504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:03:59.067601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:03:59.067642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgPreVoteResp from 1ee87564e61d159f at term 1"} {"level":"info","ts":"2026-08-12T11:03:59.067664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:03:59.067678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgVoteResp from 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-08-12T11:03:59.067709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became leader at term 2"} {"level":"info","ts":"2026-08-12T11:03:59.067726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee87564e61d159f elected leader 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-08-12T11:03:59.069164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:59.06984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee87564e61d159f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.102:2379]}","request-path":"/0/members/1ee87564e61d159f/attributes","cluster-id":"92a92aec402e97c3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:03:59.07004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:03:59.070162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:03:59.070615Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:03:59.07066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:03:59.07116Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:59.071298Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:59.071335Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:59.072584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:03:59.08003Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.102:2379"} {"level":"info","ts":"2026-08-12T11:04:02.811529Z","caller":"traceutil/trace.go:171","msg":"trace[253505866] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"346.186636ms","start":"2026-08-12T11:04:02.465315Z","end":"2026-08-12T11:04:02.811502Z","steps":["trace[253505866] 'read index received' (duration: 14.770812ms)","trace[253505866] 'applied index is now lower than readState.Index' (duration: 331.414994ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:02.811689Z","caller":"traceutil/trace.go:171","msg":"trace[903591803] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"352.818568ms","start":"2026-08-12T11:04:02.45886Z","end":"2026-08-12T11:04:02.811678Z","steps":["trace[903591803] 'process raft request' (duration: 352.488067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:02.812159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.816736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:02.812266Z","caller":"traceutil/trace.go:171","msg":"trace[485872155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:248; }","duration":"346.954301ms","start":"2026-08-12T11:04:02.465289Z","end":"2026-08-12T11:04:02.812243Z","steps":["trace[485872155] 'agreement among raft nodes before linearized reading' (duration: 346.814566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:02.812303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:02.465278Z","time spent":"347.015162ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"warn","ts":"2026-08-12T11:04:02.813201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:02.458842Z","time spent":"352.87802ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:03.341605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472141628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T11:04:03.81788Z","caller":"traceutil/trace.go:171","msg":"trace[1680062310] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"977.097705ms","start":"2026-08-12T11:04:02.840734Z","end":"2026-08-12T11:04:03.817832Z","steps":["trace[1680062310] 'read index received' (duration: 973.545462ms)","trace[1680062310] 'applied index is now lower than readState.Index' (duration: 3.551103ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:03.818018Z","caller":"traceutil/trace.go:171","msg":"trace[840223808] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"997.26131ms","start":"2026-08-12T11:04:02.82071Z","end":"2026-08-12T11:04:03.817971Z","steps":["trace[840223808] 'process raft request' (duration: 993.667005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:03.81811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.573026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:03.818143Z","caller":"traceutil/trace.go:171","msg":"trace[707900319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:250; }","duration":"448.634398ms","start":"2026-08-12T11:04:03.369496Z","end":"2026-08-12T11:04:03.818131Z","steps":["trace[707900319] 'agreement among raft nodes before linearized reading' (duration: 448.598637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:03.818187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:03.369477Z","time spent":"448.70174ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:04:03.818203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:02.820693Z","time spent":"997.419974ms","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:03.818065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.307062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:03.818309Z","caller":"traceutil/trace.go:171","msg":"trace[1829220799] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:250; }","duration":"977.575491ms","start":"2026-08-12T11:04:02.840707Z","end":"2026-08-12T11:04:03.818283Z","steps":["trace[1829220799] 'agreement among raft nodes before linearized reading' (duration: 977.303352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:03.818369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:02.840664Z","time spent":"977.689484ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-08-12T11:04:35.532251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.211921ms","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-08-12T11:04:35.532389Z","caller":"traceutil/trace.go:171","msg":"trace[1849100279] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:489; }","duration":"285.356045ms","start":"2026-08-12T11:04:35.247013Z","end":"2026-08-12T11:04:35.532369Z","steps":["trace[1849100279] 'range keys from in-memory index tree' (duration: 285.19829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:35.532444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.806468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-08-12T11:04:35.532541Z","caller":"traceutil/trace.go:171","msg":"trace[321690524] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:489; }","duration":"611.954264ms","start":"2026-08-12T11:04:34.920567Z","end":"2026-08-12T11:04:35.532522Z","steps":["trace[321690524] 'range keys from in-memory index tree' (duration: 611.668735ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:35.532546Z","caller":"traceutil/trace.go:171","msg":"trace[2114270417] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"196.18971ms","start":"2026-08-12T11:04:35.336322Z","end":"2026-08-12T11:04:35.532511Z","steps":["trace[2114270417] 'process raft request' (duration: 181.262542ms)","trace[2114270417] 'compare' (duration: 14.493165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:35.532576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:34.92054Z","time spent":"612.028066ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-08-12T11:04:35.532187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.288155ms","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-08-12T11:04:35.534415Z","caller":"traceutil/trace.go:171","msg":"trace[1657294128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:489; }","duration":"585.572207ms","start":"2026-08-12T11:04:34.948824Z","end":"2026-08-12T11:04:35.534396Z","steps":["trace[1657294128] 'range keys from in-memory index tree' (duration: 583.187461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:35.534476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:34.948804Z","time spent":"585.654498ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T11:04:39.002131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472142239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:04:39.068402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.037524504s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T11:04:39.068875Z","caller":"traceutil/trace.go:171","msg":"trace[1693468974] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.038101793s","start":"2026-08-12T11:04:38.030749Z","end":"2026-08-12T11:04:39.068851Z","steps":["trace[1693468974] 'process raft request' (duration: 1.037926627s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.068993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.030725Z","time spent":"1.038204265s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.090675Z","caller":"traceutil/trace.go:171","msg":"trace[560525878] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.056415676s","start":"2026-08-12T11:04:38.034237Z","end":"2026-08-12T11:04:39.090652Z","steps":["trace[560525878] 'process raft request' (duration: 1.05622462s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.090827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.034218Z","time spent":"1.05653565s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:39.09093Z","caller":"traceutil/trace.go:171","msg":"trace[1420958928] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.051300876s","start":"2026-08-12T11:04:38.039598Z","end":"2026-08-12T11:04:39.090899Z","steps":["trace[1420958928] 'process raft request' (duration: 1.050987886s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.039583Z","time spent":"1.05141683s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091144Z","caller":"traceutil/trace.go:171","msg":"trace[1375163131] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.043962206s","start":"2026-08-12T11:04:38.047161Z","end":"2026-08-12T11:04:39.091123Z","steps":["trace[1375163131] 'process raft request' (duration: 1.043670947s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:39.091205Z","caller":"traceutil/trace.go:171","msg":"trace[711603164] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.043685047s","start":"2026-08-12T11:04:38.047513Z","end":"2026-08-12T11:04:39.091198Z","steps":["trace[711603164] 'process raft request' (duration: 1.04345427s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.047509Z","time spent":"1.043725358s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091156Z","caller":"traceutil/trace.go:171","msg":"trace[1710839697] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.043673996s","start":"2026-08-12T11:04:38.047471Z","end":"2026-08-12T11:04:39.091145Z","steps":["trace[1710839697] 'process raft request' (duration: 1.04345542s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.047141Z","time spent":"1.044056409s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:04:39.091323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.047463Z","time spent":"1.043833942s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091393Z","caller":"traceutil/trace.go:171","msg":"trace[597043482] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.032750405s","start":"2026-08-12T11:04:38.058619Z","end":"2026-08-12T11:04:39.09137Z","steps":["trace[597043482] 'process raft request' (duration: 1.032588799s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.058597Z","time spent":"1.032821687s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091466Z","caller":"traceutil/trace.go:171","msg":"trace[1220015679] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.033511078s","start":"2026-08-12T11:04:38.057878Z","end":"2026-08-12T11:04:39.091389Z","steps":["trace[1220015679] 'process raft request' (duration: 1.03325972s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:39.091522Z","caller":"traceutil/trace.go:171","msg":"trace[1188382125] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.025212888s","start":"2026-08-12T11:04:38.066301Z","end":"2026-08-12T11:04:39.091514Z","steps":["trace[1188382125] 'process raft request' (duration: 1.025007362s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.066278Z","time spent":"1.02526364s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:04:39.091606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.057866Z","time spent":"1.033678564s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091628Z","caller":"traceutil/trace.go:171","msg":"trace[2110808371] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.022514983s","start":"2026-08-12T11:04:38.069106Z","end":"2026-08-12T11:04:39.091621Z","steps":["trace[2110808371] 'process raft request' (duration: 1.022257225s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.09167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.06909Z","time spent":"1.022564415s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:39.091695Z","caller":"traceutil/trace.go:171","msg":"trace[1774368734] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.020380287s","start":"2026-08-12T11:04:38.071291Z","end":"2026-08-12T11:04:39.091672Z","steps":["trace[1774368734] 'process raft request' (duration: 1.020205651s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:39.091702Z","caller":"traceutil/trace.go:171","msg":"trace[2088316923] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:554; }","duration":"589.891662ms","start":"2026-08-12T11:04:38.501768Z","end":"2026-08-12T11:04:39.09166Z","steps":["trace[2088316923] 'read index received' (duration: 566.974103ms)","trace[2088316923] 'applied index is now lower than readState.Index' (duration: 22.916699ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:39.091751Z","caller":"traceutil/trace.go:171","msg":"trace[10437036] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"891.59949ms","start":"2026-08-12T11:04:38.200136Z","end":"2026-08-12T11:04:39.091735Z","steps":["trace[10437036] 'process raft request' (duration: 891.449565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.091782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.071275Z","time spent":"1.02047818s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:04:39.091831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.200109Z","time spent":"891.686923ms","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:04:39.09196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.178621ms","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-08-12T11:04:39.092039Z","caller":"traceutil/trace.go:171","msg":"trace[811358128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"590.296455ms","start":"2026-08-12T11:04:38.501716Z","end":"2026-08-12T11:04:39.092012Z","steps":["trace[811358128] 'agreement among raft nodes before linearized reading' (duration: 590.088358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.092082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.501693Z","time spent":"590.380348ms","remote":"127.0.0.1:53972","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":"info","ts":"2026-08-12T11:04:39.091556Z","caller":"traceutil/trace.go:171","msg":"trace[1345053371] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.033965092s","start":"2026-08-12T11:04:38.057369Z","end":"2026-08-12T11:04:39.091334Z","steps":["trace[1345053371] 'process raft request' (duration: 1.033725765s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.092312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.05733Z","time spent":"1.034918832s","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:04:39.095397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.656483ms","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-08-12T11:04:39.095444Z","caller":"traceutil/trace.go:171","msg":"trace[933536498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"239.748356ms","start":"2026-08-12T11:04:38.855684Z","end":"2026-08-12T11:04:39.095432Z","steps":["trace[933536498] 'agreement among raft nodes before linearized reading' (duration: 239.582071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.299129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.318098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cqg2n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-12T11:04:45.299213Z","caller":"traceutil/trace.go:171","msg":"trace[539949413] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cqg2n; range_end:; response_count:1; response_revision:636; }","duration":"354.448101ms","start":"2026-08-12T11:04:44.944745Z","end":"2026-08-12T11:04:45.299193Z","steps":["trace[539949413] 'range keys from in-memory index tree' (duration: 354.139742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.299299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:44.944723Z","time spent":"354.564565ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-cqg2n\" "} {"level":"info","ts":"2026-08-12T11:04:45.415601Z","caller":"traceutil/trace.go:171","msg":"trace[1254997550] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"289.436813ms","start":"2026-08-12T11:04:45.126111Z","end":"2026-08-12T11:04:45.415547Z","steps":["trace[1254997550] 'read index received' (duration: 288.369809ms)","trace[1254997550] 'applied index is now lower than readState.Index' (duration: 1.065954ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:45.41567Z","caller":"traceutil/trace.go:171","msg":"trace[1156742480] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"387.479686ms","start":"2026-08-12T11:04:45.028153Z","end":"2026-08-12T11:04:45.415633Z","steps":["trace[1156742480] 'process raft request' (duration: 386.767764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.415813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.694811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T11:04:45.415865Z","caller":"traceutil/trace.go:171","msg":"trace[493839596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"289.823325ms","start":"2026-08-12T11:04:45.126031Z","end":"2026-08-12T11:04:45.415854Z","steps":["trace[493839596] 'agreement among raft nodes before linearized reading' (duration: 289.669271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.415853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.028123Z","time spent":"387.623271ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:45.633731Z","caller":"traceutil/trace.go:171","msg":"trace[743839573] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"217.994744ms","start":"2026-08-12T11:04:45.415707Z","end":"2026-08-12T11:04:45.633702Z","steps":["trace[743839573] 'read index received' (duration: 213.516663ms)","trace[743839573] 'applied index is now lower than readState.Index' (duration: 4.477151ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:45.633801Z","caller":"traceutil/trace.go:171","msg":"trace[631193110] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"320.851908ms","start":"2026-08-12T11:04:45.312924Z","end":"2026-08-12T11:04:45.633776Z","steps":["trace[631193110] 'process raft request' (duration: 316.300525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.633926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.312901Z","time spent":"320.944541ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:45.634042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.492592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-12T11:04:45.634041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.818599ms","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-08-12T11:04:45.634096Z","caller":"traceutil/trace.go:171","msg":"trace[861055775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"487.615385ms","start":"2026-08-12T11:04:45.146464Z","end":"2026-08-12T11:04:45.634079Z","steps":["trace[861055775] 'agreement among raft nodes before linearized reading' (duration: 487.381518ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:45.634117Z","caller":"traceutil/trace.go:171","msg":"trace[168035149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"492.946653ms","start":"2026-08-12T11:04:45.141157Z","end":"2026-08-12T11:04:45.634104Z","steps":["trace[168035149] 'agreement among raft nodes before linearized reading' (duration: 492.766658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:45.63414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.146453Z","time spent":"487.674798ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T11:04:45.634147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.141145Z","time spent":"492.995844ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T11:04:45.677049Z","caller":"traceutil/trace.go:171","msg":"trace[450134571] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"256.994886ms","start":"2026-08-12T11:04:45.419975Z","end":"2026-08-12T11:04:45.67697Z","steps":["trace[450134571] 'process raft request' (duration: 256.716007ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:45.677176Z","caller":"traceutil/trace.go:171","msg":"trace[1847182205] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"207.426272ms","start":"2026-08-12T11:04:45.469727Z","end":"2026-08-12T11:04:45.677153Z","steps":["trace[1847182205] 'process raft request' (duration: 207.103502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:46.192015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472142427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:04:47.699324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163890946s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:47.699524Z","caller":"traceutil/trace.go:171","msg":"trace[640068067] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.06075677s","start":"2026-08-12T11:04:45.638732Z","end":"2026-08-12T11:04:47.699488Z","steps":["trace[640068067] 'process raft request' (duration: 896.503893ms)","trace[640068067] 'compare' (duration: 1.163548035s)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:47.699608Z","caller":"traceutil/trace.go:171","msg":"trace[1489525803] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.060707488s","start":"2026-08-12T11:04:45.638873Z","end":"2026-08-12T11:04:47.69958Z","steps":["trace[1489525803] 'process raft request' (duration: 2.060540913s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.699662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.638707Z","time spent":"2.060883633s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:47.699718Z","caller":"traceutil/trace.go:171","msg":"trace[144642864] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.01834752s","start":"2026-08-12T11:04:45.681337Z","end":"2026-08-12T11:04:47.699684Z","steps":["trace[144642864] 'process raft request' (duration: 2.018160184s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.699739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.638823Z","time spent":"2.060845003s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:47.699755Z","caller":"traceutil/trace.go:171","msg":"trace[946914513] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:670; }","duration":"2.008174312s","start":"2026-08-12T11:04:45.691562Z","end":"2026-08-12T11:04:47.699736Z","steps":["trace[946914513] 'read index received' (duration: 843.681538ms)","trace[946914513] 'applied index is now lower than readState.Index' (duration: 1.164491834s)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:47.6999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.681317Z","time spent":"2.018471145s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:48.193365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.797479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T11:04:48.193462Z","caller":"traceutil/trace.go:171","msg":"trace[1427846065] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"493.661765ms","start":"2026-08-12T11:04:47.699782Z","end":"2026-08-12T11:04:48.193444Z","steps":["trace[1427846065] 'read index received' (duration: 172.146µs)","trace[1427846065] 'applied index is now lower than readState.Index' (duration: 493.488079ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:48.19353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596014871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:48.193555Z","caller":"traceutil/trace.go:171","msg":"trace[1344481660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"1.596087843s","start":"2026-08-12T11:04:46.597458Z","end":"2026-08-12T11:04:48.193546Z","steps":["trace[1344481660] 'agreement among raft nodes before linearized reading' (duration: 1.596030491s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.193584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:46.597438Z","time spent":"1.596139715s","remote":"127.0.0.1:53734","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-08-12T11:04:48.193666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.808023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T11:04:48.193724Z","caller":"traceutil/trace.go:171","msg":"trace[86179869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"511.904166ms","start":"2026-08-12T11:04:47.681803Z","end":"2026-08-12T11:04:48.193707Z","steps":["trace[86179869] 'agreement among raft nodes before linearized reading' (duration: 511.7183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.193771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.681752Z","time spent":"512.004409ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:04:48.193777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.502194907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:48.19386Z","caller":"traceutil/trace.go:171","msg":"trace[402430018] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:644; }","duration":"2.502316821s","start":"2026-08-12T11:04:45.691517Z","end":"2026-08-12T11:04:48.193834Z","steps":["trace[402430018] 'agreement among raft nodes before linearized reading' (duration: 2.008424179s)","trace[402430018] 'range keys from in-memory index tree' (duration: 493.777828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:48.193912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.691498Z","time spent":"2.502404334s","remote":"127.0.0.1:53958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-08-12T11:04:50.407118Z","caller":"traceutil/trace.go:171","msg":"trace[1096560642] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"195.376435ms","start":"2026-08-12T11:04:50.211724Z","end":"2026-08-12T11:04:50.4071Z","steps":["trace[1096560642] 'read index received' (duration: 195.355324ms)","trace[1096560642] 'applied index is now lower than readState.Index' (duration: 20.621µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:50.407176Z","caller":"traceutil/trace.go:171","msg":"trace[1631190579] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"206.171373ms","start":"2026-08-12T11:04:50.200984Z","end":"2026-08-12T11:04:50.407155Z","steps":["trace[1631190579] 'process raft request' (duration: 206.037339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:50.407465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.723865ms","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-08-12T11:04:50.407499Z","caller":"traceutil/trace.go:171","msg":"trace[1950625019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"195.806569ms","start":"2026-08-12T11:04:50.211683Z","end":"2026-08-12T11:04:50.40749Z","steps":["trace[1950625019] 'agreement among raft nodes before linearized reading' (duration: 195.487839ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:00.149566Z","caller":"traceutil/trace.go:171","msg":"trace[1459145880] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"110.048269ms","start":"2026-08-12T11:05:00.03949Z","end":"2026-08-12T11:05:00.149538Z","steps":["trace[1459145880] 'read index received' (duration: 105.251629ms)","trace[1459145880] 'applied index is now lower than readState.Index' (duration: 4.79463ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:00.14961Z","caller":"traceutil/trace.go:171","msg":"trace[475726483] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"300.367906ms","start":"2026-08-12T11:04:59.849216Z","end":"2026-08-12T11:05:00.149584Z","steps":["trace[475726483] 'process raft request' (duration: 295.454762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:00.149805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:59.849177Z","time spent":"300.524191ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:00.150314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.293557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-n9n2s\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-08-12T11:05:00.150392Z","caller":"traceutil/trace.go:171","msg":"trace[823129311] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-n9n2s; range_end:; response_count:1; response_revision:784; }","duration":"110.906927ms","start":"2026-08-12T11:05:00.039465Z","end":"2026-08-12T11:05:00.150372Z","steps":["trace[823129311] 'agreement among raft nodes before linearized reading' (duration: 110.182124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:03.652269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.690328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:05:03.652806Z","caller":"traceutil/trace.go:171","msg":"trace[1093100807] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"1.057057926s","start":"2026-08-12T11:05:02.595722Z","end":"2026-08-12T11:05:03.65278Z","steps":["trace[1093100807] 'read index received' (duration: 375.677426ms)","trace[1093100807] 'applied index is now lower than readState.Index' (duration: 681.37899ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:03.652838Z","caller":"traceutil/trace.go:171","msg":"trace[1548107866] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.184979736s","start":"2026-08-12T11:05:02.467814Z","end":"2026-08-12T11:05:03.652794Z","steps":["trace[1548107866] 'process raft request' (duration: 503.679749ms)","trace[1548107866] 'compare' (duration: 680.508072ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:03.652988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:02.467787Z","time spent":"1.185124301s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:03.652989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05720168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:03.65316Z","caller":"traceutil/trace.go:171","msg":"trace[1489715099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"1.057452009s","start":"2026-08-12T11:05:02.595679Z","end":"2026-08-12T11:05:03.653131Z","steps":["trace[1489715099] 'agreement among raft nodes before linearized reading' (duration: 1.05716488s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:03.653397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:02.595658Z","time spent":"1.057724957s","remote":"127.0.0.1:53734","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-08-12T11:05:03.661939Z","caller":"traceutil/trace.go:171","msg":"trace[1238306239] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"597.918703ms","start":"2026-08-12T11:05:03.064001Z","end":"2026-08-12T11:05:03.66192Z","steps":["trace[1238306239] 'process raft request' (duration: 597.79831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:03.662027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:03.063976Z","time spent":"598.005505ms","remote":"127.0.0.1:53958","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-08-12T11:05:17.806656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.296595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T11:05:17.80682Z","caller":"traceutil/trace.go:171","msg":"trace[1907722194] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"323.842652ms","start":"2026-08-12T11:05:17.48296Z","end":"2026-08-12T11:05:17.806803Z","steps":["trace[1907722194] 'read index received' (duration: 126.335351ms)","trace[1907722194] 'applied index is now lower than readState.Index' (duration: 197.505831ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:17.807039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.073479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kbnjq\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-08-12T11:05:17.807091Z","caller":"traceutil/trace.go:171","msg":"trace[1822434716] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kbnjq; range_end:; response_count:1; response_revision:937; }","duration":"324.124631ms","start":"2026-08-12T11:05:17.482944Z","end":"2026-08-12T11:05:17.807069Z","steps":["trace[1822434716] 'agreement among raft nodes before linearized reading' (duration: 323.965306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:17.80713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:17.482868Z","time spent":"324.253945ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kbnjq\" "} {"level":"warn","ts":"2026-08-12T11:05:17.807192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.848511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:17.807268Z","caller":"traceutil/trace.go:171","msg":"trace[1082775820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"211.972245ms","start":"2026-08-12T11:05:17.595279Z","end":"2026-08-12T11:05:17.807251Z","steps":["trace[1082775820] 'agreement among raft nodes before linearized reading' (duration: 211.836801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:18.202797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.352768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:05:18.203009Z","caller":"traceutil/trace.go:171","msg":"trace[1564650981] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:946; }","duration":"139.657678ms","start":"2026-08-12T11:05:18.063328Z","end":"2026-08-12T11:05:18.202986Z","steps":["trace[1564650981] 'count revisions from in-memory index tree' (duration: 139.266475ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:49.247359Z","caller":"traceutil/trace.go:171","msg":"trace[1894263264] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"106.822025ms","start":"2026-08-12T11:05:49.140516Z","end":"2026-08-12T11:05:49.247338Z","steps":["trace[1894263264] 'process raft request' (duration: 106.68104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:50.061033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.423139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:05:50.061059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.093984ms","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-08-12T11:05:50.061111Z","caller":"traceutil/trace.go:171","msg":"trace[845295598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"464.589274ms","start":"2026-08-12T11:05:49.596504Z","end":"2026-08-12T11:05:50.061094Z","steps":["trace[845295598] 'range keys from in-memory index tree' (duration: 464.335346ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:50.061133Z","caller":"traceutil/trace.go:171","msg":"trace[1484988659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"118.222618ms","start":"2026-08-12T11:05:49.942895Z","end":"2026-08-12T11:05:50.061118Z","steps":["trace[1484988659] 'range keys from in-memory index tree' (duration: 117.867036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:50.061154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:49.59648Z","time spent":"464.665217ms","remote":"127.0.0.1:53734","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-08-12T11:05:50.061347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.981104ms","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-08-12T11:05:50.061445Z","caller":"traceutil/trace.go:171","msg":"trace[494361673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"102.120108ms","start":"2026-08-12T11:05:49.9593Z","end":"2026-08-12T11:05:50.06142Z","steps":["trace[494361673] 'range keys from in-memory index tree' (duration: 101.84971ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:50.306927Z","caller":"traceutil/trace.go:171","msg":"trace[1867413811] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"241.08979ms","start":"2026-08-12T11:05:50.065813Z","end":"2026-08-12T11:05:50.306902Z","steps":["trace[1867413811] 'process raft request' (duration: 240.902864ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:50.342774Z","caller":"traceutil/trace.go:171","msg":"trace[194781632] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"276.348954ms","start":"2026-08-12T11:05:50.066396Z","end":"2026-08-12T11:05:50.342745Z","steps":["trace[194781632] 'process raft request' (duration: 276.295553ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:50.342842Z","caller":"traceutil/trace.go:171","msg":"trace[370206267] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"276.51364ms","start":"2026-08-12T11:05:50.066298Z","end":"2026-08-12T11:05:50.342812Z","steps":["trace[370206267] 'process raft request' (duration: 276.360135ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:50.342829Z","caller":"traceutil/trace.go:171","msg":"trace[743202053] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"276.571931ms","start":"2026-08-12T11:05:50.066212Z","end":"2026-08-12T11:05:50.342784Z","steps":["trace[743202053] 'process raft request' (duration: 276.327513ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:51.568826Z","caller":"traceutil/trace.go:171","msg":"trace[4142034] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"316.137739ms","start":"2026-08-12T11:05:51.252659Z","end":"2026-08-12T11:05:51.568797Z","steps":["trace[4142034] 'process raft request' (duration: 315.944033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:51.569054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:51.252633Z","time spent":"316.299144ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:05:53.871956Z","caller":"traceutil/trace.go:171","msg":"trace[1398082828] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"811.310182ms","start":"2026-08-12T11:05:53.06062Z","end":"2026-08-12T11:05:53.87193Z","steps":["trace[1398082828] 'process raft request' (duration: 811.123786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:53.872244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:53.060602Z","time spent":"811.551619ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:05:53.884117Z","caller":"traceutil/trace.go:171","msg":"trace[399372255] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"274.710573ms","start":"2026-08-12T11:05:53.60935Z","end":"2026-08-12T11:05:53.88406Z","steps":["trace[399372255] 'read index received' (duration: 262.433872ms)","trace[399372255] 'applied index is now lower than readState.Index' (duration: 12.275791ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:53.884188Z","caller":"traceutil/trace.go:171","msg":"trace[1530778706] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"309.890105ms","start":"2026-08-12T11:05:53.574283Z","end":"2026-08-12T11:05:53.884174Z","steps":["trace[1530778706] 'process raft request' (duration: 309.639737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:53.884328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:53.574256Z","time spent":"310.031298ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:53.884383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.997122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:05:53.884445Z","caller":"traceutil/trace.go:171","msg":"trace[594641345] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1070; }","duration":"275.114905ms","start":"2026-08-12T11:05:53.609301Z","end":"2026-08-12T11:05:53.884416Z","steps":["trace[594641345] 'agreement among raft nodes before linearized reading' (duration: 274.914059ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:08.766826Z","caller":"traceutil/trace.go:171","msg":"trace[1624074449] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"279.365078ms","start":"2026-08-12T11:06:08.487439Z","end":"2026-08-12T11:06:08.766804Z","steps":["trace[1624074449] 'process raft request' (duration: 279.217303ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:08.766988Z","caller":"traceutil/trace.go:171","msg":"trace[115474824] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"279.522653ms","start":"2026-08-12T11:06:08.487444Z","end":"2026-08-12T11:06:08.766967Z","steps":["trace[115474824] 'process raft request' (duration: 279.327127ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:08.768615Z","caller":"traceutil/trace.go:171","msg":"trace[635558402] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1222; }","duration":"139.695904ms","start":"2026-08-12T11:06:08.6289Z","end":"2026-08-12T11:06:08.768596Z","steps":["trace[635558402] 'read index received' (duration: 137.859457ms)","trace[635558402] 'applied index is now lower than readState.Index' (duration: 1.835597ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:08.768786Z","caller":"traceutil/trace.go:171","msg":"trace[538129372] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"281.192294ms","start":"2026-08-12T11:06:08.48758Z","end":"2026-08-12T11:06:08.768773Z","steps":["trace[538129372] 'process raft request' (duration: 280.944686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:08.771005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.093648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T11:06:08.771062Z","caller":"traceutil/trace.go:171","msg":"trace[744525570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"142.204961ms","start":"2026-08-12T11:06:08.628843Z","end":"2026-08-12T11:06:08.771048Z","steps":["trace[744525570] 'agreement among raft nodes before linearized reading' (duration: 139.820747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:10.987538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472143617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:06:11.272458Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342086118s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T11:06:11.272727Z","caller":"traceutil/trace.go:171","msg":"trace[136813057] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"786.155242ms","start":"2026-08-12T11:06:10.486558Z","end":"2026-08-12T11:06:11.272713Z","steps":["trace[136813057] 'read index received' (duration: 785.980156ms)","trace[136813057] 'applied index is now lower than readState.Index' (duration: 174.495µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:11.272966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.48015ms","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-08-12T11:06:11.272988Z","caller":"traceutil/trace.go:171","msg":"trace[484912003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"502.540502ms","start":"2026-08-12T11:06:10.770439Z","end":"2026-08-12T11:06:11.27298Z","steps":["trace[484912003] 'agreement among raft nodes before linearized reading' (duration: 502.434178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.272958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.380558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-12T11:06:11.27303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.770424Z","time spent":"502.600803ms","remote":"127.0.0.1:53972","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-08-12T11:06:11.273054Z","caller":"traceutil/trace.go:171","msg":"trace[1985468385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1174; }","duration":"786.536103ms","start":"2026-08-12T11:06:10.486498Z","end":"2026-08-12T11:06:11.273034Z","steps":["trace[1985468385] 'agreement among raft nodes before linearized reading' (duration: 786.310766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.273097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.486474Z","time spent":"786.614315ms","remote":"127.0.0.1:53972","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-08-12T11:06:11.273135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.899236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:11.273149Z","caller":"traceutil/trace.go:171","msg":"trace[1522371773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"300.939337ms","start":"2026-08-12T11:06:10.972205Z","end":"2026-08-12T11:06:11.273144Z","steps":["trace[1522371773] 'agreement among raft nodes before linearized reading' (duration: 300.917176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.27316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.972189Z","time spent":"300.969078ms","remote":"127.0.0.1:53734","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-08-12T11:06:11.273265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.808737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T11:06:11.273281Z","caller":"traceutil/trace.go:171","msg":"trace[1741828612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"489.841588ms","start":"2026-08-12T11:06:10.783435Z","end":"2026-08-12T11:06:11.273277Z","steps":["trace[1741828612] 'agreement among raft nodes before linearized reading' (duration: 489.792657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.273294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.783426Z","time spent":"489.864118ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-12T11:06:11.273317Z","caller":"traceutil/trace.go:171","msg":"trace[1018922212] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"1.343015047s","start":"2026-08-12T11:06:09.930289Z","end":"2026-08-12T11:06:11.273304Z","steps":["trace[1018922212] 'process raft request' (duration: 1.342319355s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.273381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.558667ms","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-08-12T11:06:11.273393Z","caller":"traceutil/trace.go:171","msg":"trace[1603203008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"497.63751ms","start":"2026-08-12T11:06:10.775752Z","end":"2026-08-12T11:06:11.27339Z","steps":["trace[1603203008] 'agreement among raft nodes before linearized reading' (duration: 497.600298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.273405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.775731Z","time spent":"497.671251ms","remote":"127.0.0.1:53972","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-08-12T11:06:11.273407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:09.930266Z","time spent":"1.343078699s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:11.273476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.406487ms","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-08-12T11:06:11.273488Z","caller":"traceutil/trace.go:171","msg":"trace[1818724934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1174; }","duration":"502.431018ms","start":"2026-08-12T11:06:10.771054Z","end":"2026-08-12T11:06:11.273485Z","steps":["trace[1818724934] 'agreement among raft nodes before linearized reading' (duration: 502.394817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:11.2735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:10.771046Z","time spent":"502.451549ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T11:06:17.479999Z","caller":"traceutil/trace.go:171","msg":"trace[722913868] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"125.140832ms","start":"2026-08-12T11:06:17.354836Z","end":"2026-08-12T11:06:17.479976Z","steps":["trace[722913868] 'read index received' (duration: 124.923386ms)","trace[722913868] 'applied index is now lower than readState.Index' (duration: 216.426µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:17.480089Z","caller":"traceutil/trace.go:171","msg":"trace[370249255] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"177.450535ms","start":"2026-08-12T11:06:17.302595Z","end":"2026-08-12T11:06:17.480045Z","steps":["trace[370249255] 'process raft request' (duration: 177.189097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:17.48028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.41522ms","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-08-12T11:06:17.480333Z","caller":"traceutil/trace.go:171","msg":"trace[1107177873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"125.527024ms","start":"2026-08-12T11:06:17.354795Z","end":"2026-08-12T11:06:17.480322Z","steps":["trace[1107177873] 'agreement among raft nodes before linearized reading' (duration: 125.347448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:17.554781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.632284ms","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-08-12T11:06:17.554841Z","caller":"traceutil/trace.go:171","msg":"trace[1637289167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"190.713437ms","start":"2026-08-12T11:06:17.364114Z","end":"2026-08-12T11:06:17.554827Z","steps":["trace[1637289167] 'agreement among raft nodes before linearized reading' (duration: 190.305944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:17.5549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.522858ms","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-08-12T11:06:17.554934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.171437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T11:06:17.554955Z","caller":"traceutil/trace.go:171","msg":"trace[276589810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"177.204638ms","start":"2026-08-12T11:06:17.377744Z","end":"2026-08-12T11:06:17.554949Z","steps":["trace[276589810] 'agreement among raft nodes before linearized reading' (duration: 176.689882ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:17.554965Z","caller":"traceutil/trace.go:171","msg":"trace[608528157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"177.61365ms","start":"2026-08-12T11:06:17.377333Z","end":"2026-08-12T11:06:17.554947Z","steps":["trace[608528157] 'agreement among raft nodes before linearized reading' (duration: 177.112895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:17.555057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.602481ms","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-08-12T11:06:17.555076Z","caller":"traceutil/trace.go:171","msg":"trace[1152551777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1196; }","duration":"177.621271ms","start":"2026-08-12T11:06:17.377449Z","end":"2026-08-12T11:06:17.55507Z","steps":["trace[1152551777] 'agreement among raft nodes before linearized reading' (duration: 176.985511ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:17.669771Z","caller":"traceutil/trace.go:171","msg":"trace[1038946147] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"110.398314ms","start":"2026-08-12T11:06:17.55934Z","end":"2026-08-12T11:06:17.669738Z","steps":["trace[1038946147] 'process raft request' (duration: 110.086074ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:17.670057Z","caller":"traceutil/trace.go:171","msg":"trace[1980591713] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"110.633901ms","start":"2026-08-12T11:06:17.559405Z","end":"2026-08-12T11:06:17.670039Z","steps":["trace[1980591713] 'process raft request' (duration: 110.142956ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:17.670124Z","caller":"traceutil/trace.go:171","msg":"trace[2130720041] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"109.358612ms","start":"2026-08-12T11:06:17.560745Z","end":"2026-08-12T11:06:17.670104Z","steps":["trace[2130720041] 'process raft request' (duration: 109.220958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:19.957271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.020964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:06:19.957362Z","caller":"traceutil/trace.go:171","msg":"trace[1282621536] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"362.874349ms","start":"2026-08-12T11:06:19.594473Z","end":"2026-08-12T11:06:19.957347Z","steps":["trace[1282621536] 'read index received' (duration: 154.571746ms)","trace[1282621536] 'applied index is now lower than readState.Index' (duration: 208.301353ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:19.957388Z","caller":"traceutil/trace.go:171","msg":"trace[378437559] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"393.415976ms","start":"2026-08-12T11:06:19.563939Z","end":"2026-08-12T11:06:19.957354Z","steps":["trace[378437559] 'process raft request' (duration: 185.039281ms)","trace[378437559] 'compare' (duration: 207.90792ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:19.957468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.999092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:19.957495Z","caller":"traceutil/trace.go:171","msg":"trace[1014468243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"363.065354ms","start":"2026-08-12T11:06:19.59442Z","end":"2026-08-12T11:06:19.957486Z","steps":["trace[1014468243] 'agreement among raft nodes before linearized reading' (duration: 363.013592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:19.957531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.594401Z","time spent":"363.122986ms","remote":"127.0.0.1:53734","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-08-12T11:06:19.957534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.563907Z","time spent":"393.54326ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:19.957598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.816141ms","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-08-12T11:06:19.95767Z","caller":"traceutil/trace.go:171","msg":"trace[155531664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"308.950525ms","start":"2026-08-12T11:06:19.648702Z","end":"2026-08-12T11:06:19.957652Z","steps":["trace[155531664] 'agreement among raft nodes before linearized reading' (duration: 308.749879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:19.957709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.648681Z","time spent":"309.019618ms","remote":"127.0.0.1:53972","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-08-12T11:06:19.957863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.772083ms","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-08-12T11:06:19.957889Z","caller":"traceutil/trace.go:171","msg":"trace[18257411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"282.813054ms","start":"2026-08-12T11:06:19.675066Z","end":"2026-08-12T11:06:19.95788Z","steps":["trace[18257411] 'agreement among raft nodes before linearized reading' (duration: 282.738842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:19.957971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.772234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T11:06:19.95801Z","caller":"traceutil/trace.go:171","msg":"trace[1662216467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"283.827816ms","start":"2026-08-12T11:06:19.674172Z","end":"2026-08-12T11:06:19.957999Z","steps":["trace[1662216467] 'agreement among raft nodes before linearized reading' (duration: 283.681471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:19.958075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.102285ms","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-08-12T11:06:19.958146Z","caller":"traceutil/trace.go:171","msg":"trace[326660910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"284.198977ms","start":"2026-08-12T11:06:19.673935Z","end":"2026-08-12T11:06:19.958134Z","steps":["trace[326660910] 'agreement among raft nodes before linearized reading' (duration: 284.016151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:20.581179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.155807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:06:20.581291Z","caller":"traceutil/trace.go:171","msg":"trace[2119426451] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"619.205491ms","start":"2026-08-12T11:06:19.962066Z","end":"2026-08-12T11:06:20.581272Z","steps":["trace[2119426451] 'process raft request' (duration: 168.8749ms)","trace[2119426451] 'compare' (duration: 449.772224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:20.581349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.962046Z","time spent":"619.276094ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:20.620727Z","caller":"traceutil/trace.go:171","msg":"trace[954456034] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"658.130849ms","start":"2026-08-12T11:06:19.962571Z","end":"2026-08-12T11:06:20.620702Z","steps":["trace[954456034] 'process raft request' (duration: 657.619633ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:20.620741Z","caller":"traceutil/trace.go:171","msg":"trace[1086406911] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"658.203541ms","start":"2026-08-12T11:06:19.962517Z","end":"2026-08-12T11:06:20.62072Z","steps":["trace[1086406911] 'process raft request' (duration: 657.556801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:20.620856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.962499Z","time spent":"658.302935ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:20.62087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.96256Z","time spent":"658.232743ms","remote":"127.0.0.1:53972","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-08-12T11:06:20.621148Z","caller":"traceutil/trace.go:171","msg":"trace[44024596] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"658.528751ms","start":"2026-08-12T11:06:19.962598Z","end":"2026-08-12T11:06:20.621127Z","steps":["trace[44024596] 'process raft request' (duration: 657.682495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:20.621192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:19.962582Z","time spent":"658.588213ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:22.044041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.340422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-12T11:06:22.044097Z","caller":"traceutil/trace.go:171","msg":"trace[900946304] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:1; response_revision:1207; }","duration":"158.428695ms","start":"2026-08-12T11:06:21.885653Z","end":"2026-08-12T11:06:22.044082Z","steps":["trace[900946304] 'range keys from in-memory index tree' (duration: 158.229019ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:22.286297Z","caller":"traceutil/trace.go:171","msg":"trace[941048560] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"195.374122ms","start":"2026-08-12T11:06:22.090885Z","end":"2026-08-12T11:06:22.286259Z","steps":["trace[941048560] 'process raft request' (duration: 147.916029ms)","trace[941048560] 'compare' (duration: 47.299998ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:24.76672Z","caller":"traceutil/trace.go:171","msg":"trace[1122443788] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"113.104919ms","start":"2026-08-12T11:06:24.653488Z","end":"2026-08-12T11:06:24.766592Z","steps":["trace[1122443788] 'process raft request' (duration: 112.979405ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:24.768599Z","caller":"traceutil/trace.go:171","msg":"trace[957131376] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"111.333714ms","start":"2026-08-12T11:06:24.657255Z","end":"2026-08-12T11:06:24.768589Z","steps":["trace[957131376] 'process raft request' (duration: 111.238211ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:28.738603Z","caller":"traceutil/trace.go:171","msg":"trace[1447736740] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"101.698436ms","start":"2026-08-12T11:06:28.63686Z","end":"2026-08-12T11:06:28.738558Z","steps":["trace[1447736740] 'process raft request' (duration: 101.341444ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:36.369272Z","caller":"traceutil/trace.go:171","msg":"trace[1771719641] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"134.024267ms","start":"2026-08-12T11:06:36.235223Z","end":"2026-08-12T11:06:36.369247Z","steps":["trace[1771719641] 'process raft request' (duration: 132.821839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:38.417205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472143922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:06:38.918229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558139873472143922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:06:39.038135Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.355702257s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T11:06:39.03859Z","caller":"traceutil/trace.go:171","msg":"trace[1128547616] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.356204113s","start":"2026-08-12T11:06:37.682363Z","end":"2026-08-12T11:06:39.038568Z","steps":["trace[1128547616] 'process raft request' (duration: 1.355977716s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.038713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:37.682354Z","time spent":"1.356287945s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:39.229348Z","caller":"traceutil/trace.go:171","msg":"trace[1496950314] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.481116741s","start":"2026-08-12T11:06:37.74819Z","end":"2026-08-12T11:06:39.229307Z","steps":["trace[1496950314] 'process raft request' (duration: 1.48076723s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:39.22963Z","caller":"traceutil/trace.go:171","msg":"trace[1173480682] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1382; }","duration":"1.31340946s","start":"2026-08-12T11:06:37.916191Z","end":"2026-08-12T11:06:39.229601Z","steps":["trace[1173480682] 'read index received' (duration: 1.122174605s)","trace[1173480682] 'applied index is now lower than readState.Index' (duration: 191.233705ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:39.229673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:37.74816Z","time spent":"1.48139528s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:39.229765Z","caller":"traceutil/trace.go:171","msg":"trace[1079882360] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"1.087708964s","start":"2026-08-12T11:06:38.142044Z","end":"2026-08-12T11:06:39.229753Z","steps":["trace[1079882360] 'process raft request' (duration: 1.087435136s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:39.22986Z","caller":"traceutil/trace.go:171","msg":"trace[1146914127] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.197084002s","start":"2026-08-12T11:06:38.032722Z","end":"2026-08-12T11:06:39.229806Z","steps":["trace[1146914127] 'process raft request' (duration: 1.196507084s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.229909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.142015Z","time spent":"1.087785007s","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:39.230009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.03269Z","time spent":"1.197241487s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:39.230266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.353416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-12T11:06:39.230286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.670971ms","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-08-12T11:06:39.230319Z","caller":"traceutil/trace.go:171","msg":"trace[157375453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"196.44519ms","start":"2026-08-12T11:06:39.03386Z","end":"2026-08-12T11:06:39.230305Z","steps":["trace[157375453] 'agreement among raft nodes before linearized reading' (duration: 196.271314ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:39.230352Z","caller":"traceutil/trace.go:171","msg":"trace[1452689586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"856.778015ms","start":"2026-08-12T11:06:38.373556Z","end":"2026-08-12T11:06:39.230334Z","steps":["trace[1452689586] 'agreement among raft nodes before linearized reading' (duration: 856.547707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.230411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.373537Z","time spent":"856.861717ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T11:06:39.230461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.74073ms","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-08-12T11:06:39.230499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.340311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:39.230544Z","caller":"traceutil/trace.go:171","msg":"trace[1338111341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"633.428524ms","start":"2026-08-12T11:06:38.597101Z","end":"2026-08-12T11:06:39.23053Z","steps":["trace[1338111341] 'agreement among raft nodes before linearized reading' (duration: 633.358172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.230567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.445988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T11:06:39.230585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.597073Z","time spent":"633.506056ms","remote":"127.0.0.1:53734","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-08-12T11:06:39.230606Z","caller":"traceutil/trace.go:171","msg":"trace[1196745927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"139.52626ms","start":"2026-08-12T11:06:39.091069Z","end":"2026-08-12T11:06:39.230595Z","steps":["trace[1196745927] 'agreement among raft nodes before linearized reading' (duration: 139.418707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.23061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.462383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:06:39.230654Z","caller":"traceutil/trace.go:171","msg":"trace[437225788] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1328; }","duration":"285.510865ms","start":"2026-08-12T11:06:38.945135Z","end":"2026-08-12T11:06:39.230646Z","steps":["trace[437225788] 'agreement among raft nodes before linearized reading' (duration: 285.440082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.230661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314470193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-12T11:06:39.230537Z","caller":"traceutil/trace.go:171","msg":"trace[218083753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"389.869114ms","start":"2026-08-12T11:06:38.840651Z","end":"2026-08-12T11:06:39.23052Z","steps":["trace[218083753] 'agreement among raft nodes before linearized reading' (duration: 389.638836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.230729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.84063Z","time spent":"390.090431ms","remote":"127.0.0.1:53972","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-08-12T11:06:39.230733Z","caller":"traceutil/trace.go:171","msg":"trace[1667195976] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"1.314536675s","start":"2026-08-12T11:06:37.916174Z","end":"2026-08-12T11:06:39.230711Z","steps":["trace[1667195976] 'agreement among raft nodes before linearized reading' (duration: 1.31439707s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.23078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:37.916113Z","time spent":"1.314654778s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-12T11:06:39.230873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.704913ms","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-08-12T11:06:39.231001Z","caller":"traceutil/trace.go:171","msg":"trace[2043813481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"348.857657ms","start":"2026-08-12T11:06:38.882103Z","end":"2026-08-12T11:06:39.23096Z","steps":["trace[2043813481] 'agreement among raft nodes before linearized reading' (duration: 348.173886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:39.231973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:38.882082Z","time spent":"349.864549ms","remote":"127.0.0.1:53972","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-08-12T11:08:24.02457Z","caller":"traceutil/trace.go:171","msg":"trace[2104595990] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"117.944258ms","start":"2026-08-12T11:08:23.906604Z","end":"2026-08-12T11:08:24.024548Z","steps":["trace[2104595990] 'process raft request' (duration: 117.849125ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:34.039336Z","caller":"traceutil/trace.go:171","msg":"trace[1499313935] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"101.319249ms","start":"2026-08-12T11:08:33.938001Z","end":"2026-08-12T11:08:34.03932Z","steps":["trace[1499313935] 'read index received' (duration: 101.181575ms)","trace[1499313935] 'applied index is now lower than readState.Index' (duration: 137.164µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:08:34.039613Z","caller":"traceutil/trace.go:171","msg":"trace[725935032] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"110.46141ms","start":"2026-08-12T11:08:33.929144Z","end":"2026-08-12T11:08:34.039605Z","steps":["trace[725935032] 'process raft request' (duration: 110.087478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:34.03981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.805575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-12T11:08:34.039836Z","caller":"traceutil/trace.go:171","msg":"trace[2113335829] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1855; }","duration":"101.877047ms","start":"2026-08-12T11:08:33.937953Z","end":"2026-08-12T11:08:34.03983Z","steps":["trace[2113335829] 'agreement among raft nodes before linearized reading' (duration: 101.784314ms)"],"step_count":1}