{"level":"warn","ts":"2026-03-29T01:53:46.52822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:53:46.528326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-03-29T01:53:46.528397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:53:46.528408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-03-29T01:53:46.528425Z","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-03-29T01:53:46.529105Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-03-29T01:53:46.529174Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-03-29T01:53:46.593281Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"63.87857ms"} {"level":"info","ts":"2026-03-29T01:53:46.629295Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-03-29T01:53:46.629409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-03-29T01:53:46.629433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-03-29T01:53:46.629453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-29T01:53:46.629462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-03-29T01:53:46.6296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-03-29T01:53:46.657129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-29T01:53:46.661713Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-29T01:53:46.670898Z","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-03-29T01:53:46.677682Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-29T01:53:46.677898Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-29T01:53:46.678756Z","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-03-29T01:53:46.679028Z","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-03-29T01:53:46.679086Z","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-03-29T01:53:46.679724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-03-29T01:53:46.680326Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-03-29T01:53:46.68244Z","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-03-29T01:53:46.682683Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-29T01:53:46.68272Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-29T01:53:46.683675Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-03-29T01:53:46.683712Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-03-29T01:53:47.530744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-03-29T01:53:47.530817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-03-29T01:53:47.530866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-03-29T01:53:47.530901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-03-29T01:53:47.530959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-03-29T01:53:47.530989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-03-29T01:53:47.531011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-03-29T01:53:47.574918Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:47.584978Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-29T01:53:47.585207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:53:47.586067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:53:47.586993Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:47.587124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:47.58716Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:47.58746Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-29T01:53:47.587484Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-29T01:53:47.588387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-29T01:53:47.588759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"warn","ts":"2026-03-29T01:53:47.758498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.789522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T01:53:47.758593Z","caller":"traceutil/trace.go:171","msg":"trace[1878651680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"106.893924ms","start":"2026-03-29T01:53:47.65168Z","end":"2026-03-29T01:53:47.758574Z","steps":["trace[1878651680] 'range keys from in-memory index tree' (duration: 106.546569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:47.758909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.089137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T01:53:47.759002Z","caller":"traceutil/trace.go:171","msg":"trace[568731344] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"107.224648ms","start":"2026-03-29T01:53:47.651761Z","end":"2026-03-29T01:53:47.758986Z","steps":["trace[568731344] 'count revisions from in-memory index tree' (duration: 106.892515ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.198717Z","caller":"traceutil/trace.go:171","msg":"trace[1573479246] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"107.624456ms","start":"2026-03-29T01:53:49.091059Z","end":"2026-03-29T01:53:49.198683Z","steps":["trace[1573479246] 'process raft request' (duration: 87.056336ms)","trace[1573479246] 'compare' (duration: 20.155676ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:53:49.199297Z","caller":"traceutil/trace.go:171","msg":"trace[416409292] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"105.731645ms","start":"2026-03-29T01:53:49.093543Z","end":"2026-03-29T01:53:49.199274Z","steps":["trace[416409292] 'process raft request' (duration: 105.30008ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.200077Z","caller":"traceutil/trace.go:171","msg":"trace[902292025] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"106.296912ms","start":"2026-03-29T01:53:49.093742Z","end":"2026-03-29T01:53:49.200039Z","steps":["trace[902292025] 'process raft request' (duration: 105.424872ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.200294Z","caller":"traceutil/trace.go:171","msg":"trace[1294771204] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"105.479692ms","start":"2026-03-29T01:53:49.094784Z","end":"2026-03-29T01:53:49.200264Z","steps":["trace[1294771204] 'process raft request' (duration: 105.346241ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.200313Z","caller":"traceutil/trace.go:171","msg":"trace[1955808877] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"105.689385ms","start":"2026-03-29T01:53:49.094607Z","end":"2026-03-29T01:53:49.200296Z","steps":["trace[1955808877] 'process raft request' (duration: 105.460032ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.200359Z","caller":"traceutil/trace.go:171","msg":"trace[497003758] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"106.596964ms","start":"2026-03-29T01:53:49.093681Z","end":"2026-03-29T01:53:49.200278Z","steps":["trace[497003758] 'process raft request' (duration: 106.262641ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:49.2002Z","caller":"traceutil/trace.go:171","msg":"trace[815862464] linearizableReadLoop","detail":"{readStateIndex:37; appliedIndex:35; }","duration":"106.556624ms","start":"2026-03-29T01:53:49.09362Z","end":"2026-03-29T01:53:49.200177Z","steps":["trace[815862464] 'read index received' (duration: 84.609178ms)","trace[815862464] 'applied index is now lower than readState.Index' (duration: 21.946096ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:49.200778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.18112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T01:53:49.200863Z","caller":"traceutil/trace.go:171","msg":"trace[1365065582] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:37; }","duration":"106.356032ms","start":"2026-03-29T01:53:49.094489Z","end":"2026-03-29T01:53:49.200845Z","steps":["trace[1365065582] 'agreement among raft nodes before linearized reading' (duration: 106.1845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:49.200926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.0799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T01:53:49.201007Z","caller":"traceutil/trace.go:171","msg":"trace[1531059504] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:37; }","duration":"107.402773ms","start":"2026-03-29T01:53:49.09358Z","end":"2026-03-29T01:53:49.200983Z","steps":["trace[1531059504] 'agreement among raft nodes before linearized reading' (duration: 106.801447ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:52.193485Z","caller":"traceutil/trace.go:171","msg":"trace[664338270] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"240.021115ms","start":"2026-03-29T01:53:51.953433Z","end":"2026-03-29T01:53:52.193454Z","steps":["trace[664338270] 'read index received' (duration: 239.59818ms)","trace[664338270] 'applied index is now lower than readState.Index' (duration: 421.855µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:52.193623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.182788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:52.193605Z","caller":"traceutil/trace.go:171","msg":"trace[382204068] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"328.438844ms","start":"2026-03-29T01:53:51.865132Z","end":"2026-03-29T01:53:52.193571Z","steps":["trace[382204068] 'process raft request' (duration: 328.058408ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:52.193663Z","caller":"traceutil/trace.go:171","msg":"trace[646731702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:251; }","duration":"240.31299ms","start":"2026-03-29T01:53:51.953338Z","end":"2026-03-29T01:53:52.193651Z","steps":["trace[646731702] 'agreement among raft nodes before linearized reading' (duration: 240.225609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:52.194855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:51.865078Z","time spent":"328.619986ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:53:52.632058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.240677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:53:52.632249Z","caller":"traceutil/trace.go:171","msg":"trace[1683312764] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"429.313369ms","start":"2026-03-29T01:53:52.20292Z","end":"2026-03-29T01:53:52.632233Z","steps":["trace[1683312764] 'process raft request' (duration: 429.241518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:52.632307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:52.202896Z","time spent":"429.38842ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:53:52.632563Z","caller":"traceutil/trace.go:171","msg":"trace[1622060939] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"433.527117ms","start":"2026-03-29T01:53:52.199002Z","end":"2026-03-29T01:53:52.63253Z","steps":["trace[1622060939] 'process raft request' (duration: 29.539192ms)","trace[1622060939] 'compare' (duration: 403.100215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:52.632735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:52.198987Z","time spent":"433.68444ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:53:52.633367Z","caller":"traceutil/trace.go:171","msg":"trace[1621361302] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"106.89044ms","start":"2026-03-29T01:53:52.526465Z","end":"2026-03-29T01:53:52.633355Z","steps":["trace[1621361302] 'process raft request' (duration: 106.705378ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:52.797091Z","caller":"traceutil/trace.go:171","msg":"trace[254637377] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"151.682079ms","start":"2026-03-29T01:53:52.645375Z","end":"2026-03-29T01:53:52.797057Z","steps":["trace[254637377] 'read index received' (duration: 115.647031ms)","trace[254637377] 'applied index is now lower than readState.Index' (duration: 36.033518ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:53:52.797147Z","caller":"traceutil/trace.go:171","msg":"trace[529181710] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"159.837904ms","start":"2026-03-29T01:53:52.637285Z","end":"2026-03-29T01:53:52.797122Z","steps":["trace[529181710] 'process raft request' (duration: 123.643744ms)","trace[529181710] 'compare' (duration: 35.963307ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:52.797314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.32018ms","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-03-29T01:53:52.797368Z","caller":"traceutil/trace.go:171","msg":"trace[414586490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:256; }","duration":"161.404651ms","start":"2026-03-29T01:53:52.63595Z","end":"2026-03-29T01:53:52.797355Z","steps":["trace[414586490] 'agreement among raft nodes before linearized reading' (duration: 161.249319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:52.797682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.516713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:52.797832Z","caller":"traceutil/trace.go:171","msg":"trace[1204494575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:256; }","duration":"161.733895ms","start":"2026-03-29T01:53:52.63606Z","end":"2026-03-29T01:53:52.797794Z","steps":["trace[1204494575] 'agreement among raft nodes before linearized reading' (duration: 161.152269ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:52.805554Z","caller":"traceutil/trace.go:171","msg":"trace[380622388] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"155.559014ms","start":"2026-03-29T01:53:52.649976Z","end":"2026-03-29T01:53:52.805535Z","steps":["trace[380622388] 'process raft request' (duration: 155.468803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.415083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.16997ms","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-03-29T01:53:59.415138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.820929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:59.415181Z","caller":"traceutil/trace.go:171","msg":"trace[1568928360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"563.327881ms","start":"2026-03-29T01:53:58.851834Z","end":"2026-03-29T01:53:59.415162Z","steps":["trace[1568928360] 'range keys from in-memory index tree' (duration: 563.044308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.415207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.470588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-29T01:53:59.41523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:58.851809Z","time spent":"563.410234ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-29T01:53:59.415233Z","caller":"traceutil/trace.go:171","msg":"trace[116294107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"217.969172ms","start":"2026-03-29T01:53:59.197241Z","end":"2026-03-29T01:53:59.41521Z","steps":["trace[116294107] 'range keys from in-memory index tree' (duration: 217.722748ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:59.415265Z","caller":"traceutil/trace.go:171","msg":"trace[403835391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"121.54836ms","start":"2026-03-29T01:53:59.293703Z","end":"2026-03-29T01:53:59.415252Z","steps":["trace[403835391] 'range keys from in-memory index tree' (duration: 121.403857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.41604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.493259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:53:59.416268Z","caller":"traceutil/trace.go:171","msg":"trace[1995693942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"129.472739ms","start":"2026-03-29T01:53:59.28677Z","end":"2026-03-29T01:53:59.416243Z","steps":["trace[1995693942] 'range keys from in-memory index tree' (duration: 128.372867ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.043084Z","caller":"traceutil/trace.go:171","msg":"trace[1154626766] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"620.134238ms","start":"2026-03-29T01:53:59.422926Z","end":"2026-03-29T01:54:00.04306Z","steps":["trace[1154626766] 'process raft request' (duration: 619.842444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.043244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.422909Z","time spent":"620.254599ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:00.218668Z","caller":"traceutil/trace.go:171","msg":"trace[595644287] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"795.364246ms","start":"2026-03-29T01:53:59.423273Z","end":"2026-03-29T01:54:00.218637Z","steps":["trace[595644287] 'process raft request' (duration: 721.710836ms)","trace[595644287] 'compare' (duration: 73.459448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:00.218977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.423246Z","time spent":"795.502417ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:00.218988Z","caller":"traceutil/trace.go:171","msg":"trace[1059789143] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:402; }","duration":"510.135036ms","start":"2026-03-29T01:53:59.708827Z","end":"2026-03-29T01:54:00.218962Z","steps":["trace[1059789143] 'read index received' (duration: 333.961937ms)","trace[1059789143] 'applied index is now lower than readState.Index' (duration: 176.171859ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.218993Z","caller":"traceutil/trace.go:171","msg":"trace[854872549] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"795.66578ms","start":"2026-03-29T01:53:59.423296Z","end":"2026-03-29T01:54:00.218962Z","steps":["trace[854872549] 'process raft request' (duration: 795.299906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.219179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.423272Z","time spent":"795.807441ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:00.219212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.346138ms","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-03-29T01:54:00.219252Z","caller":"traceutil/trace.go:171","msg":"trace[250371054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"510.465411ms","start":"2026-03-29T01:53:59.708773Z","end":"2026-03-29T01:54:00.219238Z","steps":["trace[250371054] 'agreement among raft nodes before linearized reading' (duration: 510.276068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.219293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.708752Z","time spent":"510.533381ms","remote":"127.0.0.1:39956","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-03-29T01:54:00.219543Z","caller":"traceutil/trace.go:171","msg":"trace[652135429] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"771.742437ms","start":"2026-03-29T01:53:59.447778Z","end":"2026-03-29T01:54:00.219521Z","steps":["trace[652135429] 'process raft request' (duration: 771.075389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.219641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.44776Z","time spent":"771.836108ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:00.584951Z","caller":"traceutil/trace.go:171","msg":"trace[417471426] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"115.507908ms","start":"2026-03-29T01:54:00.469418Z","end":"2026-03-29T01:54:00.584926Z","steps":["trace[417471426] 'read index received' (duration: 115.235925ms)","trace[417471426] 'applied index is now lower than readState.Index' (duration: 270.553µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.584989Z","caller":"traceutil/trace.go:171","msg":"trace[1985316266] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"236.093853ms","start":"2026-03-29T01:54:00.348862Z","end":"2026-03-29T01:54:00.584956Z","steps":["trace[1985316266] 'process raft request' (duration: 235.903711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.585151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.70853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-29T01:54:00.585195Z","caller":"traceutil/trace.go:171","msg":"trace[1380862419] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:397; }","duration":"115.77173ms","start":"2026-03-29T01:54:00.469411Z","end":"2026-03-29T01:54:00.585183Z","steps":["trace[1380862419] 'agreement among raft nodes before linearized reading' (duration: 115.616529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:01.696322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703480,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:01.940156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211498889s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T01:54:02.282989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.517043ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-29T01:54:02.283101Z","caller":"traceutil/trace.go:171","msg":"trace[41357623] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"1.087238102s","start":"2026-03-29T01:54:01.195845Z","end":"2026-03-29T01:54:02.283083Z","steps":["trace[41357623] 'read index received' (duration: 744.632327ms)","trace[41357623] 'applied index is now lower than readState.Index' (duration: 342.604175ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:02.28322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087372963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:54:02.283306Z","caller":"traceutil/trace.go:171","msg":"trace[644198724] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:397; }","duration":"1.087452244s","start":"2026-03-29T01:54:01.195794Z","end":"2026-03-29T01:54:02.283246Z","steps":["trace[644198724] 'agreement among raft nodes before linearized reading' (duration: 1.087360093s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.2835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:01.195772Z","time spent":"1.087573896s","remote":"127.0.0.1:39698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-03-29T01:54:02.283177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:00.728427Z","time spent":"1.554733871s","remote":"127.0.0.1:40208","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-29T01:54:02.287119Z","caller":"traceutil/trace.go:171","msg":"trace[602032294] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"470.189737ms","start":"2026-03-29T01:54:01.816849Z","end":"2026-03-29T01:54:02.287039Z","steps":["trace[602032294] 'process raft request' (duration: 469.987485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.287215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.249639ms","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-03-29T01:54:02.287282Z","caller":"traceutil/trace.go:171","msg":"trace[1517931965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"239.332381ms","start":"2026-03-29T01:54:02.047925Z","end":"2026-03-29T01:54:02.287257Z","steps":["trace[1517931965] 'agreement among raft nodes before linearized reading' (duration: 239.2542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.287324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.867385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-29T01:54:02.28746Z","caller":"traceutil/trace.go:171","msg":"trace[708627903] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:398; }","duration":"825.049027ms","start":"2026-03-29T01:54:01.462382Z","end":"2026-03-29T01:54:02.287431Z","steps":["trace[708627903] 'agreement among raft nodes before linearized reading' (duration: 824.823304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.287518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:01.462348Z","time spent":"825.155898ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-29T01:54:02.287125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089938522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:02.287769Z","caller":"traceutil/trace.go:171","msg":"trace[2003410573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"1.09065453s","start":"2026-03-29T01:54:01.197086Z","end":"2026-03-29T01:54:02.28774Z","steps":["trace[2003410573] 'agreement among raft nodes before linearized reading' (duration: 1.089940622s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.287846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:01.197065Z","time spent":"1.090755501s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:02.287369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:01.816816Z","time spent":"470.409689ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:02.457128Z","caller":"traceutil/trace.go:171","msg":"trace[1343685585] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"164.280906ms","start":"2026-03-29T01:54:02.292805Z","end":"2026-03-29T01:54:02.457086Z","steps":["trace[1343685585] 'process raft request' (duration: 164.199365ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:02.457174Z","caller":"traceutil/trace.go:171","msg":"trace[1978316753] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"164.754721ms","start":"2026-03-29T01:54:02.29237Z","end":"2026-03-29T01:54:02.457124Z","steps":["trace[1978316753] 'process raft request' (duration: 164.603699ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:02.4572Z","caller":"traceutil/trace.go:171","msg":"trace[1876657185] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"164.881483ms","start":"2026-03-29T01:54:02.292294Z","end":"2026-03-29T01:54:02.457176Z","steps":["trace[1876657185] 'process raft request' (duration: 155.670358ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:02.457285Z","caller":"traceutil/trace.go:171","msg":"trace[121726239] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"164.158185ms","start":"2026-03-29T01:54:02.29312Z","end":"2026-03-29T01:54:02.457278Z","steps":["trace[121726239] 'process raft request' (duration: 163.902852ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:02.457418Z","caller":"traceutil/trace.go:171","msg":"trace[883267759] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:412; }","duration":"161.461244ms","start":"2026-03-29T01:54:02.295949Z","end":"2026-03-29T01:54:02.45741Z","steps":["trace[883267759] 'read index received' (duration: 2.894333ms)","trace[883267759] 'applied index is now lower than readState.Index' (duration: 158.563661ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:02.457583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.613517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:02.457617Z","caller":"traceutil/trace.go:171","msg":"trace[711764456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"161.663197ms","start":"2026-03-29T01:54:02.295944Z","end":"2026-03-29T01:54:02.457607Z","steps":["trace[711764456] 'agreement among raft nodes before linearized reading' (duration: 161.566616ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:02.457597Z","caller":"traceutil/trace.go:171","msg":"trace[2033799600] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"160.833178ms","start":"2026-03-29T01:54:02.296702Z","end":"2026-03-29T01:54:02.457535Z","steps":["trace[2033799600] 'process raft request' (duration: 160.340641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.457772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.884853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-29T01:54:02.457876Z","caller":"traceutil/trace.go:171","msg":"trace[1546027997] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:405; }","duration":"157.008644ms","start":"2026-03-29T01:54:02.300841Z","end":"2026-03-29T01:54:02.45785Z","steps":["trace[1546027997] 'agreement among raft nodes before linearized reading' (duration: 156.777772ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.088402Z","caller":"traceutil/trace.go:171","msg":"trace[573316019] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"202.369779ms","start":"2026-03-29T01:54:02.886011Z","end":"2026-03-29T01:54:03.088381Z","steps":["trace[573316019] 'process raft request' (duration: 202.229617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.424333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.434259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:03.424435Z","caller":"traceutil/trace.go:171","msg":"trace[1568156977] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"326.852147ms","start":"2026-03-29T01:54:03.097566Z","end":"2026-03-29T01:54:03.424418Z","steps":["trace[1568156977] 'process raft request' (duration: 226.211175ms)","trace[1568156977] 'compare' (duration: 100.117195ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:03.424494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:03.097556Z","time spent":"326.911097ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:22.886422Z","caller":"traceutil/trace.go:171","msg":"trace[2041347730] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"164.604969ms","start":"2026-03-29T01:54:22.721796Z","end":"2026-03-29T01:54:22.886401Z","steps":["trace[2041347730] 'read index received' (duration: 164.396716ms)","trace[2041347730] 'applied index is now lower than readState.Index' (duration: 207.643µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:22.886556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.73886ms","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-03-29T01:54:22.886533Z","caller":"traceutil/trace.go:171","msg":"trace[643013366] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"178.948177ms","start":"2026-03-29T01:54:22.707545Z","end":"2026-03-29T01:54:22.886493Z","steps":["trace[643013366] 'process raft request' (duration: 178.705974ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:22.886585Z","caller":"traceutil/trace.go:171","msg":"trace[1290960903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"164.798251ms","start":"2026-03-29T01:54:22.721777Z","end":"2026-03-29T01:54:22.886576Z","steps":["trace[1290960903] 'agreement among raft nodes before linearized reading' (duration: 164.729491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:22.886629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.957489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:54:22.886875Z","caller":"traceutil/trace.go:171","msg":"trace[1911161568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"141.202112ms","start":"2026-03-29T01:54:22.745666Z","end":"2026-03-29T01:54:22.886868Z","steps":["trace[1911161568] 'agreement among raft nodes before linearized reading' (duration: 140.938449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:24.857471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48492255s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:24.857752Z","caller":"traceutil/trace.go:171","msg":"trace[138187555] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:505; }","duration":"1.660501517s","start":"2026-03-29T01:54:23.197227Z","end":"2026-03-29T01:54:24.857729Z","steps":["trace[138187555] 'read index received' (duration: 175.049082ms)","trace[138187555] 'applied index is now lower than readState.Index' (duration: 1.485451175s)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:24.858014Z","caller":"traceutil/trace.go:171","msg":"trace[1712526083] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.966050801s","start":"2026-03-29T01:54:22.891944Z","end":"2026-03-29T01:54:24.857995Z","steps":["trace[1712526083] 'process raft request' (duration: 480.498314ms)","trace[1712526083] 'compare' (duration: 1.484718598s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:24.8581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:22.891899Z","time spent":"1.966154782s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:24.858093Z","caller":"traceutil/trace.go:171","msg":"trace[1060905696] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.964834868s","start":"2026-03-29T01:54:22.893215Z","end":"2026-03-29T01:54:24.85805Z","steps":["trace[1060905696] 'process raft request' (duration: 1.964468104s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:24.858244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:22.893187Z","time spent":"1.964975309s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:24.858366Z","caller":"traceutil/trace.go:171","msg":"trace[1301067838] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.965145861s","start":"2026-03-29T01:54:22.893184Z","end":"2026-03-29T01:54:24.858329Z","steps":["trace[1301067838] 'process raft request' (duration: 1.964404233s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:24.858376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661118153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:24.85846Z","caller":"traceutil/trace.go:171","msg":"trace[256544654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"1.661255635s","start":"2026-03-29T01:54:23.197181Z","end":"2026-03-29T01:54:24.858437Z","steps":["trace[256544654] 'agreement among raft nodes before linearized reading' (duration: 1.661087884s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:24.858483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:22.893168Z","time spent":"1.965258732s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:24.858519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:23.19716Z","time spent":"1.661337106s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:25.358248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703678,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:25.454727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003816882s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:25.487518Z","caller":"traceutil/trace.go:171","msg":"trace[1450169551] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"629.631611ms","start":"2026-03-29T01:54:24.857793Z","end":"2026-03-29T01:54:25.487424Z","steps":["trace[1450169551] 'read index received' (duration: 597.165935ms)","trace[1450169551] 'applied index is now lower than readState.Index' (duration: 32.459286ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:25.48774Z","caller":"traceutil/trace.go:171","msg":"trace[548101261] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.036891764s","start":"2026-03-29T01:54:24.450813Z","end":"2026-03-29T01:54:25.487705Z","steps":["trace[548101261] 'process raft request' (duration: 1.004246636s)","trace[548101261] 'compare' (duration: 31.620646ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:25.487911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:24.450791Z","time spent":"1.037036865s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:25.487923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.544898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:25.487969Z","caller":"traceutil/trace.go:171","msg":"trace[1994555987] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:490; }","duration":"793.737651ms","start":"2026-03-29T01:54:24.694219Z","end":"2026-03-29T01:54:25.487957Z","steps":["trace[1994555987] 'agreement among raft nodes before linearized reading' (duration: 793.534058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:25.488016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:24.6942Z","time spent":"793.804981ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-29T01:54:25.488311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.863973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:54:25.48833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.607174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:25.488409Z","caller":"traceutil/trace.go:171","msg":"trace[470838749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"821.722745ms","start":"2026-03-29T01:54:24.666666Z","end":"2026-03-29T01:54:25.488389Z","steps":["trace[470838749] 'agreement among raft nodes before linearized reading' (duration: 821.590124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:25.488402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.64341ms","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-03-29T01:54:25.488455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:24.666647Z","time spent":"821.800217ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:54:25.488492Z","caller":"traceutil/trace.go:171","msg":"trace[1335966328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"596.777141ms","start":"2026-03-29T01:54:24.891701Z","end":"2026-03-29T01:54:25.488478Z","steps":["trace[1335966328] 'agreement among raft nodes before linearized reading' (duration: 596.64193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:25.488566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:24.891681Z","time spent":"596.874612ms","remote":"127.0.0.1:39956","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-03-29T01:54:25.488428Z","caller":"traceutil/trace.go:171","msg":"trace[1207248049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"628.989065ms","start":"2026-03-29T01:54:24.859389Z","end":"2026-03-29T01:54:25.488378Z","steps":["trace[1207248049] 'agreement among raft nodes before linearized reading' (duration: 628.827433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:25.488712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:24.859373Z","time spent":"629.325498ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:27.999135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:28.500125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:29.000914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:29.064307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.690877564s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:29.064836Z","caller":"traceutil/trace.go:171","msg":"trace[915243325] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.691484841s","start":"2026-03-29T01:54:27.373324Z","end":"2026-03-29T01:54:29.064808Z","steps":["trace[915243325] 'process raft request' (duration: 1.691283419s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.064969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.373305Z","time spent":"1.691601182s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.080841Z","caller":"traceutil/trace.go:171","msg":"trace[337739788] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.685865369s","start":"2026-03-29T01:54:27.394963Z","end":"2026-03-29T01:54:29.080829Z","steps":["trace[337739788] 'process raft request' (duration: 1.685533725s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.08092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.394944Z","time spent":"1.68593666s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.080954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581964894s","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-03-29T01:54:29.08099Z","caller":"traceutil/trace.go:171","msg":"trace[1160420679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"1.582046776s","start":"2026-03-29T01:54:27.498932Z","end":"2026-03-29T01:54:29.080979Z","steps":["trace[1160420679] 'agreement among raft nodes before linearized reading' (duration: 1.581933884s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.081004Z","caller":"traceutil/trace.go:171","msg":"trace[2010294939] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.702901825s","start":"2026-03-29T01:54:27.378096Z","end":"2026-03-29T01:54:29.080998Z","steps":["trace[2010294939] 'process raft request' (duration: 1.702154207s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.498899Z","time spent":"1.582120106s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-29T01:54:29.081031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.378075Z","time spent":"1.702943795s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.08112Z","caller":"traceutil/trace.go:171","msg":"trace[132756656] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.702931336s","start":"2026-03-29T01:54:27.378156Z","end":"2026-03-29T01:54:29.081088Z","steps":["trace[132756656] 'process raft request' (duration: 1.702168648s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.378152Z","time spent":"1.702997156s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.081185Z","caller":"traceutil/trace.go:171","msg":"trace[1460343670] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.686143222s","start":"2026-03-29T01:54:27.395033Z","end":"2026-03-29T01:54:29.081176Z","steps":["trace[1460343670] 'process raft request' (duration: 1.685488255s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.081225Z","caller":"traceutil/trace.go:171","msg":"trace[2030448681] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.696985179s","start":"2026-03-29T01:54:27.384233Z","end":"2026-03-29T01:54:29.081218Z","steps":["trace[2030448681] 'process raft request' (duration: 1.69612601s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.395029Z","time spent":"1.686180352s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.081254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.384219Z","time spent":"1.69702021s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.081298Z","caller":"traceutil/trace.go:171","msg":"trace[2096725950] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.696892679s","start":"2026-03-29T01:54:27.3844Z","end":"2026-03-29T01:54:29.081293Z","steps":["trace[2096725950] 'process raft request' (duration: 1.6960343s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.081304Z","caller":"traceutil/trace.go:171","msg":"trace[1047684065] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.681146167s","start":"2026-03-29T01:54:27.400151Z","end":"2026-03-29T01:54:29.081298Z","steps":["trace[1047684065] 'process raft request' (duration: 1.680411369s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.384395Z","time spent":"1.696920339s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.081337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.400133Z","time spent":"1.681188268s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.081375Z","caller":"traceutil/trace.go:171","msg":"trace[1312092313] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.693884126s","start":"2026-03-29T01:54:27.387485Z","end":"2026-03-29T01:54:29.081369Z","steps":["trace[1312092313] 'process raft request' (duration: 1.692979556s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.387478Z","time spent":"1.693913617s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.080828Z","caller":"traceutil/trace.go:171","msg":"trace[23691970] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:548; }","duration":"1.581830653s","start":"2026-03-29T01:54:27.49898Z","end":"2026-03-29T01:54:29.080811Z","steps":["trace[23691970] 'read index received' (duration: 1.565673908s)","trace[23691970] 'applied index is now lower than readState.Index' (duration: 16.156125ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:29.081474Z","caller":"traceutil/trace.go:171","msg":"trace[1757183991] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.523139784s","start":"2026-03-29T01:54:27.558327Z","end":"2026-03-29T01:54:29.081467Z","steps":["trace[1757183991] 'process raft request' (duration: 1.522429285s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.081451Z","caller":"traceutil/trace.go:171","msg":"trace[343371764] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.654153643s","start":"2026-03-29T01:54:27.427251Z","end":"2026-03-29T01:54:29.081405Z","steps":["trace[343371764] 'process raft request' (duration: 1.653429784s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.081487Z","caller":"traceutil/trace.go:171","msg":"trace[1771352313] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.679822284s","start":"2026-03-29T01:54:27.401651Z","end":"2026-03-29T01:54:29.081473Z","steps":["trace[1771352313] 'process raft request' (duration: 1.678951794s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.081504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.558304Z","time spent":"1.523188404s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.081545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.401631Z","time spent":"1.679883454s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.081578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.427231Z","time spent":"1.654279485s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:29.087439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.218883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:29.087501Z","caller":"traceutil/trace.go:171","msg":"trace[936550730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"882.332944ms","start":"2026-03-29T01:54:28.205155Z","end":"2026-03-29T01:54:29.087488Z","steps":["trace[936550730] 'agreement among raft nodes before linearized reading' (duration: 882.220843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.087817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:28.205133Z","time spent":"882.673257ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:29.088348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.662766ms","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-03-29T01:54:29.088385Z","caller":"traceutil/trace.go:171","msg":"trace[1864069598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"190.697306ms","start":"2026-03-29T01:54:28.897666Z","end":"2026-03-29T01:54:29.088363Z","steps":["trace[1864069598] 'agreement among raft nodes before linearized reading' (duration: 190.614795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.088505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.184901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:54:29.088531Z","caller":"traceutil/trace.go:171","msg":"trace[1753989447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"191.238072ms","start":"2026-03-29T01:54:28.897285Z","end":"2026-03-29T01:54:29.088524Z","steps":["trace[1753989447] 'agreement among raft nodes before linearized reading' (duration: 191.174211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.088706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.992961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:54:29.088725Z","caller":"traceutil/trace.go:171","msg":"trace[435004807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"192.048541ms","start":"2026-03-29T01:54:28.89667Z","end":"2026-03-29T01:54:29.088719Z","steps":["trace[435004807] 'agreement among raft nodes before linearized reading' (duration: 191.98824ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:29.932586Z","caller":"traceutil/trace.go:171","msg":"trace[1458463050] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"468.775421ms","start":"2026-03-29T01:54:29.463787Z","end":"2026-03-29T01:54:29.932563Z","steps":["trace[1458463050] 'process raft request' (duration: 468.71755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.932716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:29.463771Z","time spent":"468.891342ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:29.932747Z","caller":"traceutil/trace.go:171","msg":"trace[1514734660] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"479.228445ms","start":"2026-03-29T01:54:29.453464Z","end":"2026-03-29T01:54:29.932692Z","steps":["trace[1514734660] 'process raft request' (duration: 431.731709ms)","trace[1514734660] 'compare' (duration: 47.141343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:29.932952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:29.453448Z","time spent":"479.400577ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:29.979835Z","caller":"traceutil/trace.go:171","msg":"trace[2121931753] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"453.551495ms","start":"2026-03-29T01:54:29.526263Z","end":"2026-03-29T01:54:29.979814Z","steps":["trace[2121931753] 'process raft request' (duration: 453.426514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:29.98016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:29.526227Z","time spent":"453.670927ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:31.100427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.742704ms","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-03-29T01:54:31.100585Z","caller":"traceutil/trace.go:171","msg":"trace[979027509] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"273.925165ms","start":"2026-03-29T01:54:30.82664Z","end":"2026-03-29T01:54:31.100565Z","steps":["trace[979027509] 'range keys from in-memory index tree' (duration: 273.723073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.100645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.031015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"warn","ts":"2026-03-29T01:54:31.10095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.336259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:31.101078Z","caller":"traceutil/trace.go:171","msg":"trace[724889875] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"247.59475ms","start":"2026-03-29T01:54:30.85347Z","end":"2026-03-29T01:54:31.101065Z","steps":["trace[724889875] 'process raft request' (duration: 77.815576ms)","trace[724889875] 'compare' (duration: 169.217108ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:31.101586Z","caller":"traceutil/trace.go:171","msg":"trace[557242150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"272.239937ms","start":"2026-03-29T01:54:30.828519Z","end":"2026-03-29T01:54:31.100759Z","steps":["trace[557242150] 'range keys from in-memory index tree' (duration: 271.732582ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.141923Z","caller":"traceutil/trace.go:171","msg":"trace[851308672] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"286.458181ms","start":"2026-03-29T01:54:30.85539Z","end":"2026-03-29T01:54:31.141849Z","steps":["trace[851308672] 'process raft request' (duration: 286.166237ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.290622Z","caller":"traceutil/trace.go:171","msg":"trace[1856338372] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"142.515317ms","start":"2026-03-29T01:54:31.148073Z","end":"2026-03-29T01:54:31.290588Z","steps":["trace[1856338372] 'process raft request' (duration: 118.78263ms)","trace[1856338372] 'compare' (duration: 23.558785ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:31.290846Z","caller":"traceutil/trace.go:171","msg":"trace[577556037] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"141.968601ms","start":"2026-03-29T01:54:31.148856Z","end":"2026-03-29T01:54:31.290824Z","steps":["trace[577556037] 'process raft request' (duration: 141.689078ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.29109Z","caller":"traceutil/trace.go:171","msg":"trace[475755046] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:629; }","duration":"135.129266ms","start":"2026-03-29T01:54:31.155949Z","end":"2026-03-29T01:54:31.291079Z","steps":["trace[475755046] 'read index received' (duration: 25.133933ms)","trace[475755046] 'applied index is now lower than readState.Index' (duration: 109.994523ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:31.29119Z","caller":"traceutil/trace.go:171","msg":"trace[585746703] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"141.735007ms","start":"2026-03-29T01:54:31.149447Z","end":"2026-03-29T01:54:31.291182Z","steps":["trace[585746703] 'process raft request' (duration: 141.310753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.291384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.414089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-03-29T01:54:31.291428Z","caller":"traceutil/trace.go:171","msg":"trace[1373577343] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:611; }","duration":"135.49464ms","start":"2026-03-29T01:54:31.155924Z","end":"2026-03-29T01:54:31.291418Z","steps":["trace[1373577343] 'agreement among raft nodes before linearized reading' (duration: 135.340048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.342089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.828204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:54:31.342177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.109296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:54:31.342202Z","caller":"traceutil/trace.go:171","msg":"trace[1357974459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"145.953336ms","start":"2026-03-29T01:54:31.196214Z","end":"2026-03-29T01:54:31.342167Z","steps":["trace[1357974459] 'agreement among raft nodes before linearized reading' (duration: 145.828723ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.342223Z","caller":"traceutil/trace.go:171","msg":"trace[1463797336] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:612; }","duration":"145.199296ms","start":"2026-03-29T01:54:31.197013Z","end":"2026-03-29T01:54:31.342213Z","steps":["trace[1463797336] 'agreement among raft nodes before linearized reading' (duration: 145.118185ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.342697Z","caller":"traceutil/trace.go:171","msg":"trace[1397383326] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"146.440949ms","start":"2026-03-29T01:54:31.195598Z","end":"2026-03-29T01:54:31.342038Z","steps":["trace[1397383326] 'process raft request' (duration: 146.113916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:37.42316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:37.924364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410703933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:38.216033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056408428s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:38.222899Z","caller":"traceutil/trace.go:171","msg":"trace[479169696] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"1.299966318s","start":"2026-03-29T01:54:36.922857Z","end":"2026-03-29T01:54:38.222823Z","steps":["trace[479169696] 'read index received' (duration: 1.293333828s)","trace[479169696] 'applied index is now lower than readState.Index' (duration: 6.63089ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:38.222972Z","caller":"traceutil/trace.go:171","msg":"trace[1779020987] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.594186113s","start":"2026-03-29T01:54:36.628733Z","end":"2026-03-29T01:54:38.222919Z","steps":["trace[1779020987] 'process raft request' (duration: 1.588396381s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.223142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:36.628707Z","time spent":"1.594329906s","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:38.223181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.271545ms","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-03-29T01:54:38.223258Z","caller":"traceutil/trace.go:171","msg":"trace[1912885336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"995.367218ms","start":"2026-03-29T01:54:37.227867Z","end":"2026-03-29T01:54:38.223234Z","steps":["trace[1912885336] 'agreement among raft nodes before linearized reading' (duration: 995.235124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.223278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.427574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-29T01:54:38.223282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.255123ms","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-03-29T01:54:38.223331Z","caller":"traceutil/trace.go:171","msg":"trace[812399208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"910.516416ms","start":"2026-03-29T01:54:37.312799Z","end":"2026-03-29T01:54:38.223316Z","steps":["trace[812399208] 'agreement among raft nodes before linearized reading' (duration: 910.366061ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:38.223344Z","caller":"traceutil/trace.go:171","msg":"trace[1286623825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"906.358725ms","start":"2026-03-29T01:54:37.316965Z","end":"2026-03-29T01:54:38.223324Z","steps":["trace[1286623825] 'agreement among raft nodes before linearized reading' (duration: 906.159169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.223365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.312781Z","time spent":"910.577368ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:38.223385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.316955Z","time spent":"906.421008ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:54:38.22329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.227851Z","time spent":"995.43304ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-29T01:54:38.223177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300302959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t94j5\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-29T01:54:38.223492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.707779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:54:38.223662Z","caller":"traceutil/trace.go:171","msg":"trace[766703548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"908.925586ms","start":"2026-03-29T01:54:37.314674Z","end":"2026-03-29T01:54:38.223599Z","steps":["trace[766703548] 'agreement among raft nodes before linearized reading' (duration: 908.486912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.223763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.314663Z","time spent":"909.086731ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:54:38.223528Z","caller":"traceutil/trace.go:171","msg":"trace[1074956998] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t94j5; range_end:; response_count:1; response_revision:640; }","duration":"1.30067922s","start":"2026-03-29T01:54:36.922839Z","end":"2026-03-29T01:54:38.223518Z","steps":["trace[1074956998] 'agreement among raft nodes before linearized reading' (duration: 1.300162324s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.223898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:36.922767Z","time spent":"1.301117964s","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-t94j5\" "} {"level":"info","ts":"2026-03-29T01:54:38.600183Z","caller":"traceutil/trace.go:171","msg":"trace[28264624] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"372.427875ms","start":"2026-03-29T01:54:38.227732Z","end":"2026-03-29T01:54:38.60016Z","steps":["trace[28264624] 'process raft request' (duration: 372.27047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.600329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.227714Z","time spent":"372.537759ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:38.868794Z","caller":"traceutil/trace.go:171","msg":"trace[519009922] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"638.487296ms","start":"2026-03-29T01:54:38.230281Z","end":"2026-03-29T01:54:38.868768Z","steps":["trace[519009922] 'process raft request' (duration: 605.051504ms)","trace[519009922] 'compare' (duration: 33.023019ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:38.869175Z","caller":"traceutil/trace.go:171","msg":"trace[2105217194] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"638.389212ms","start":"2026-03-29T01:54:38.230766Z","end":"2026-03-29T01:54:38.869155Z","steps":["trace[2105217194] 'process raft request' (duration: 637.909967ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:38.869414Z","caller":"traceutil/trace.go:171","msg":"trace[1535982107] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"637.959889ms","start":"2026-03-29T01:54:38.231429Z","end":"2026-03-29T01:54:38.869389Z","steps":["trace[1535982107] 'process raft request' (duration: 637.686839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.869307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.23026Z","time spent":"638.979351ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:38.869425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.230751Z","time spent":"638.616049ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:38.869532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.231422Z","time spent":"638.051382ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:38.870456Z","caller":"traceutil/trace.go:171","msg":"trace[1538359874] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"632.244479ms","start":"2026-03-29T01:54:38.238194Z","end":"2026-03-29T01:54:38.870439Z","steps":["trace[1538359874] 'process raft request' (duration: 631.109803ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:38.870565Z","caller":"traceutil/trace.go:171","msg":"trace[107452175] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"574.71375ms","start":"2026-03-29T01:54:38.295832Z","end":"2026-03-29T01:54:38.870546Z","steps":["trace[107452175] 'process raft request' (duration: 574.354129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.870677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.295809Z","time spent":"574.821812ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:38.870875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:38.238168Z","time spent":"632.332812ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:40.222418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024023699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:40.222511Z","caller":"traceutil/trace.go:171","msg":"trace[980566618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.024163704s","start":"2026-03-29T01:54:39.198325Z","end":"2026-03-29T01:54:40.222489Z","steps":["trace[980566618] 'range keys from in-memory index tree' (duration: 1.023920266s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.222559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.198306Z","time spent":"1.024241597s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:54:40.268311Z","caller":"traceutil/trace.go:171","msg":"trace[1092238247] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"931.801266ms","start":"2026-03-29T01:54:39.336485Z","end":"2026-03-29T01:54:40.268287Z","steps":["trace[1092238247] 'process raft request' (duration: 931.310941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.268457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.336456Z","time spent":"931.92108ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:44.19619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00029337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-29T01:54:44.196368Z","caller":"traceutil/trace.go:171","msg":"trace[1447908856] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000528947s","start":"2026-03-29T01:54:42.195785Z","end":"2026-03-29T01:54:44.196314Z","steps":["trace[1447908856] 'agreement among raft nodes before linearized reading' (duration: 2.000321981s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.196471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:42.195767Z","time spent":"2.000688622s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/29 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-29T01:54:44.794852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.968434902s","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-03-29T01:54:44.795001Z","caller":"traceutil/trace.go:171","msg":"trace[1347834575] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:647; }","duration":"3.968592687s","start":"2026-03-29T01:54:40.826388Z","end":"2026-03-29T01:54:44.79498Z","steps":["trace[1347834575] 'range keys from in-memory index tree' (duration: 3.968421102s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.79593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.44879078s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:44.796082Z","caller":"traceutil/trace.go:171","msg":"trace[34846357] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"4.19241463s","start":"2026-03-29T01:54:40.603648Z","end":"2026-03-29T01:54:44.796062Z","steps":["trace[34846357] 'read index received' (duration: 28.091µs)","trace[34846357] 'applied index is now lower than readState.Index' (duration: 4.192384339s)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:44.796103Z","caller":"traceutil/trace.go:171","msg":"trace[783006838] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"4.473424147s","start":"2026-03-29T01:54:40.322638Z","end":"2026-03-29T01:54:44.796062Z","steps":["trace[783006838] 'process raft request' (duration: 23.75308ms)","trace[783006838] 'compare' (duration: 4.448184272s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:44.796259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:40.32261Z","time spent":"4.473577432s","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:44.796386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.192717269s","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-03-29T01:54:44.796477Z","caller":"traceutil/trace.go:171","msg":"trace[2056247407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"4.192829313s","start":"2026-03-29T01:54:40.603609Z","end":"2026-03-29T01:54:44.796439Z","steps":["trace[2056247407] 'agreement among raft nodes before linearized reading' (duration: 4.192557704s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.796528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:40.603595Z","time spent":"4.192921395s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:54:44.814206Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.399158642s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:44.814687Z","caller":"traceutil/trace.go:171","msg":"trace[1886175697] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"3.39975276s","start":"2026-03-29T01:54:41.414919Z","end":"2026-03-29T01:54:44.814672Z","steps":["trace[1886175697] 'process raft request' (duration: 3.399636066s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.814782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:41.414892Z","time spent":"3.399838473s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:44.814819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.550935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:44.814881Z","caller":"traceutil/trace.go:171","msg":"trace[251992052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"149.663678ms","start":"2026-03-29T01:54:44.665203Z","end":"2026-03-29T01:54:44.814866Z","steps":["trace[251992052] 'agreement among raft nodes before linearized reading' (duration: 149.504743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.814895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.616619465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-29T01:54:44.814955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.94051067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:54:44.814954Z","caller":"traceutil/trace.go:171","msg":"trace[1247649602] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:649; }","duration":"3.616722469s","start":"2026-03-29T01:54:41.198218Z","end":"2026-03-29T01:54:44.814941Z","steps":["trace[1247649602] 'agreement among raft nodes before linearized reading' (duration: 3.616467091s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:44.814979Z","caller":"traceutil/trace.go:171","msg":"trace[145492550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"3.940533581s","start":"2026-03-29T01:54:40.874438Z","end":"2026-03-29T01:54:44.814972Z","steps":["trace[145492550] 'agreement among raft nodes before linearized reading' (duration: 3.940449988s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.814991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:41.198186Z","time spent":"3.616795261s","remote":"127.0.0.1:39698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-03-29T01:54:44.815003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:40.874413Z","time spent":"3.940585703s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:44.815098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.480845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:54:44.81516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.941578773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:54:44.815164Z","caller":"traceutil/trace.go:171","msg":"trace[664863897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"618.594119ms","start":"2026-03-29T01:54:44.196554Z","end":"2026-03-29T01:54:44.815148Z","steps":["trace[664863897] 'agreement among raft nodes before linearized reading' (duration: 618.500956ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:44.815183Z","caller":"traceutil/trace.go:171","msg":"trace[1586481605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"3.941657105s","start":"2026-03-29T01:54:40.873519Z","end":"2026-03-29T01:54:44.815176Z","steps":["trace[1586481605] 'agreement among raft nodes before linearized reading' (duration: 3.941581133s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.815204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:40.8735Z","time spent":"3.941700117s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:44.815204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.19653Z","time spent":"618.667162ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:44.815218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.94114945s","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-03-29T01:54:44.815238Z","caller":"traceutil/trace.go:171","msg":"trace[1093895773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"3.941191001s","start":"2026-03-29T01:54:40.874041Z","end":"2026-03-29T01:54:44.815232Z","steps":["trace[1093895773] 'agreement among raft nodes before linearized reading' (duration: 3.941134409s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.815264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:40.87403Z","time spent":"3.941227463s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T01:54:45.07603Z","caller":"traceutil/trace.go:171","msg":"trace[1821894009] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"255.295727ms","start":"2026-03-29T01:54:44.820677Z","end":"2026-03-29T01:54:45.075973Z","steps":["trace[1821894009] 'process raft request' (duration: 254.964417ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.076131Z","caller":"traceutil/trace.go:171","msg":"trace[1301516722] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:675; }","duration":"253.733048ms","start":"2026-03-29T01:54:44.822343Z","end":"2026-03-29T01:54:45.076077Z","steps":["trace[1301516722] 'read index received' (duration: 71.072313ms)","trace[1301516722] 'applied index is now lower than readState.Index' (duration: 182.659595ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:45.076258Z","caller":"traceutil/trace.go:171","msg":"trace[236832699] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"254.481902ms","start":"2026-03-29T01:54:44.821761Z","end":"2026-03-29T01:54:45.076243Z","steps":["trace[236832699] 'process raft request' (duration: 254.158523ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.076364Z","caller":"traceutil/trace.go:171","msg":"trace[1846197439] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"255.38041ms","start":"2026-03-29T01:54:44.820966Z","end":"2026-03-29T01:54:45.076346Z","steps":["trace[1846197439] 'process raft request' (duration: 254.893805ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.076495Z","caller":"traceutil/trace.go:171","msg":"trace[822815942] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"254.352628ms","start":"2026-03-29T01:54:44.822136Z","end":"2026-03-29T01:54:45.076488Z","steps":["trace[822815942] 'process raft request' (duration: 253.893154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.076905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.534792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-29T01:54:45.076989Z","caller":"traceutil/trace.go:171","msg":"trace[161749357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:655; }","duration":"254.653596ms","start":"2026-03-29T01:54:44.822316Z","end":"2026-03-29T01:54:45.076969Z","steps":["trace[161749357] 'agreement among raft nodes before linearized reading' (duration: 253.929955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.262075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.240375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:45.262493Z","caller":"traceutil/trace.go:171","msg":"trace[1560210652] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"168.089667ms","start":"2026-03-29T01:54:45.094377Z","end":"2026-03-29T01:54:45.262467Z","steps":["trace[1560210652] 'process raft request' (duration: 167.969893ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.262575Z","caller":"traceutil/trace.go:171","msg":"trace[1973807547] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"176.811712ms","start":"2026-03-29T01:54:45.085725Z","end":"2026-03-29T01:54:45.262537Z","steps":["trace[1973807547] 'read index received' (duration: 66.007378ms)","trace[1973807547] 'applied index is now lower than readState.Index' (duration: 110.800644ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:45.262728Z","caller":"traceutil/trace.go:171","msg":"trace[663211768] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"178.38491ms","start":"2026-03-29T01:54:45.084323Z","end":"2026-03-29T01:54:45.262708Z","steps":["trace[663211768] 'process raft request' (duration: 67.421372ms)","trace[663211768] 'compare' (duration: 109.567405ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:45.26287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.114671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:45.262988Z","caller":"traceutil/trace.go:171","msg":"trace[1966546930] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:657; }","duration":"177.257586ms","start":"2026-03-29T01:54:45.085709Z","end":"2026-03-29T01:54:45.262967Z","steps":["trace[1966546930] 'agreement among raft nodes before linearized reading' (duration: 177.027959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.527857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.562171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:45.52803Z","caller":"traceutil/trace.go:171","msg":"trace[1560648093] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"223.0812ms","start":"2026-03-29T01:54:45.304921Z","end":"2026-03-29T01:54:45.528002Z","steps":["trace[1560648093] 'process raft request' (duration: 98.300612ms)","trace[1560648093] 'compare' (duration: 124.444187ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:45.528068Z","caller":"traceutil/trace.go:171","msg":"trace[1249645249] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"221.453261ms","start":"2026-03-29T01:54:45.306599Z","end":"2026-03-29T01:54:45.528052Z","steps":["trace[1249645249] 'process raft request' (duration: 221.399229ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.528218Z","caller":"traceutil/trace.go:171","msg":"trace[659816658] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"223.231624ms","start":"2026-03-29T01:54:45.304966Z","end":"2026-03-29T01:54:45.528197Z","steps":["trace[659816658] 'process raft request' (duration: 222.985577ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:46.079245Z","caller":"traceutil/trace.go:171","msg":"trace[788821809] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"135.604587ms","start":"2026-03-29T01:54:45.943619Z","end":"2026-03-29T01:54:46.079224Z","steps":["trace[788821809] 'process raft request' (duration: 135.479513ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:46.276247Z","caller":"traceutil/trace.go:171","msg":"trace[1710415964] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"144.147017ms","start":"2026-03-29T01:54:46.132075Z","end":"2026-03-29T01:54:46.276222Z","steps":["trace[1710415964] 'process raft request' (duration: 143.936171ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:47.278485Z","caller":"traceutil/trace.go:171","msg":"trace[122682546] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"380.098688ms","start":"2026-03-29T01:54:46.898359Z","end":"2026-03-29T01:54:47.278457Z","steps":["trace[122682546] 'read index received' (duration: 379.992864ms)","trace[122682546] 'applied index is now lower than readState.Index' (duration: 104.634µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:47.278576Z","caller":"traceutil/trace.go:171","msg":"trace[1712917930] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"451.070709ms","start":"2026-03-29T01:54:46.827476Z","end":"2026-03-29T01:54:47.278547Z","steps":["trace[1712917930] 'process raft request' (duration: 450.789321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.278703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:46.827442Z","time spent":"451.198873ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:47.278755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.379667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:54:47.278815Z","caller":"traceutil/trace.go:171","msg":"trace[1016864755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"380.48933ms","start":"2026-03-29T01:54:46.898311Z","end":"2026-03-29T01:54:47.2788Z","steps":["trace[1016864755] 'agreement among raft nodes before linearized reading' (duration: 380.281434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.278841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:46.898292Z","time spent":"380.544691ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:54:47.488758Z","caller":"traceutil/trace.go:171","msg":"trace[558390000] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"205.641202ms","start":"2026-03-29T01:54:47.283095Z","end":"2026-03-29T01:54:47.488736Z","steps":["trace[558390000] 'process raft request' (duration: 205.58228ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:47.488985Z","caller":"traceutil/trace.go:171","msg":"trace[198118769] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"210.385235ms","start":"2026-03-29T01:54:47.278559Z","end":"2026-03-29T01:54:47.488944Z","steps":["trace[198118769] 'read index received' (duration: 118.220365ms)","trace[198118769] 'applied index is now lower than readState.Index' (duration: 92.15866ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:47.489085Z","caller":"traceutil/trace.go:171","msg":"trace[358159631] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"393.099226ms","start":"2026-03-29T01:54:47.095975Z","end":"2026-03-29T01:54:47.489074Z","steps":["trace[358159631] 'process raft request' (duration: 300.789871ms)","trace[358159631] 'compare' (duration: 91.761268ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:47.489197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.095955Z","time spent":"393.189199ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":776,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:47.489251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.326556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-29T01:54:47.489257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.279431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t94j5\" ","response":"range_response_count:1 size:17431"} {"level":"info","ts":"2026-03-29T01:54:47.489301Z","caller":"traceutil/trace.go:171","msg":"trace[831248312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"409.42884ms","start":"2026-03-29T01:54:47.079854Z","end":"2026-03-29T01:54:47.489283Z","steps":["trace[831248312] 'agreement among raft nodes before linearized reading' (duration: 409.215273ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:47.489324Z","caller":"traceutil/trace.go:171","msg":"trace[763945946] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t94j5; range_end:; response_count:1; response_revision:669; }","duration":"394.372435ms","start":"2026-03-29T01:54:47.094931Z","end":"2026-03-29T01:54:47.489304Z","steps":["trace[763945946] 'agreement among raft nodes before linearized reading' (duration: 394.071795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.489304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.555563ms","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-03-29T01:54:47.489355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.079835Z","time spent":"409.509692ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:47.489362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.09492Z","time spent":"394.433027ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17453,"request content":"key:\"/registry/pods/kube-system/cilium-t94j5\" "} {"level":"info","ts":"2026-03-29T01:54:47.489377Z","caller":"traceutil/trace.go:171","msg":"trace[1821255551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"408.678356ms","start":"2026-03-29T01:54:47.080682Z","end":"2026-03-29T01:54:47.489361Z","steps":["trace[1821255551] 'agreement among raft nodes before linearized reading' (duration: 408.461239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.489421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.080661Z","time spent":"408.750829ms","remote":"127.0.0.1:39956","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-03-29T01:54:47.842793Z","caller":"traceutil/trace.go:171","msg":"trace[1463254082] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"341.059677ms","start":"2026-03-29T01:54:47.501717Z","end":"2026-03-29T01:54:47.842777Z","steps":["trace[1463254082] 'process raft request' (duration: 341.003706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.842913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.501688Z","time spent":"341.158279ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:47.842943Z","caller":"traceutil/trace.go:171","msg":"trace[459874231] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"348.44438ms","start":"2026-03-29T01:54:47.494461Z","end":"2026-03-29T01:54:47.842905Z","steps":["trace[459874231] 'process raft request' (duration: 348.132921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:47.843103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.494448Z","time spent":"348.569384ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:49.066867Z","caller":"traceutil/trace.go:171","msg":"trace[751748863] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"316.519934ms","start":"2026-03-29T01:54:48.75032Z","end":"2026-03-29T01:54:49.06684Z","steps":["trace[751748863] 'process raft request' (duration: 316.342658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:49.067012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:48.750293Z","time spent":"316.655348ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:49.522028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.95115ms","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-03-29T01:54:49.522102Z","caller":"traceutil/trace.go:171","msg":"trace[424047273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"239.063754ms","start":"2026-03-29T01:54:49.28302Z","end":"2026-03-29T01:54:49.522084Z","steps":["trace[424047273] 'range keys from in-memory index tree' (duration: 238.687643ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:49.916295Z","caller":"traceutil/trace.go:171","msg":"trace[520852488] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"390.280493ms","start":"2026-03-29T01:54:49.525988Z","end":"2026-03-29T01:54:49.916269Z","steps":["trace[520852488] 'process raft request' (duration: 390.122308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:49.916439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:49.525966Z","time spent":"390.402917ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:50.39177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.707661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:50.391969Z","caller":"traceutil/trace.go:171","msg":"trace[2141845562] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"810.789857ms","start":"2026-03-29T01:54:49.581159Z","end":"2026-03-29T01:54:50.391948Z","steps":["trace[2141845562] 'read index received' (duration: 335.076484ms)","trace[2141845562] 'applied index is now lower than readState.Index' (duration: 475.712173ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:50.392038Z","caller":"traceutil/trace.go:171","msg":"trace[2001732530] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"865.979056ms","start":"2026-03-29T01:54:49.526032Z","end":"2026-03-29T01:54:50.392011Z","steps":["trace[2001732530] 'process raft request' (duration: 626.911054ms)","trace[2001732530] 'compare' (duration: 238.558777ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:50.392054Z","caller":"traceutil/trace.go:171","msg":"trace[638649813] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"827.884688ms","start":"2026-03-29T01:54:49.564145Z","end":"2026-03-29T01:54:50.392029Z","steps":["trace[638649813] 'process raft request' (duration: 827.730573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.39215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:49.526014Z","time spent":"866.072458ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:50.392169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:49.564125Z","time spent":"827.983441ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:50.392158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.56544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:50.392215Z","caller":"traceutil/trace.go:171","msg":"trace[1563249160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"188.622362ms","start":"2026-03-29T01:54:50.203576Z","end":"2026-03-29T01:54:50.392198Z","steps":["trace[1563249160] 'agreement among raft nodes before linearized reading' (duration: 188.535039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.392216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.402317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-29T01:54:50.392242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.079516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-29T01:54:50.39226Z","caller":"traceutil/trace.go:171","msg":"trace[1198195633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"544.471758ms","start":"2026-03-29T01:54:49.847775Z","end":"2026-03-29T01:54:50.392247Z","steps":["trace[1198195633] 'agreement among raft nodes before linearized reading' (duration: 544.323614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.392269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.959977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:54:50.392289Z","caller":"traceutil/trace.go:171","msg":"trace[1888246984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"811.184119ms","start":"2026-03-29T01:54:49.58109Z","end":"2026-03-29T01:54:50.392274Z","steps":["trace[1888246984] 'agreement among raft nodes before linearized reading' (duration: 810.974543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.392305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:49.847761Z","time spent":"544.53322ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T01:54:50.39232Z","caller":"traceutil/trace.go:171","msg":"trace[1983725887] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:676; }","duration":"246.05861ms","start":"2026-03-29T01:54:50.146248Z","end":"2026-03-29T01:54:50.392306Z","steps":["trace[1983725887] 'agreement among raft nodes before linearized reading' (duration: 245.979117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.392327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:49.581071Z","time spent":"811.246901ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:50.718836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.947017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:50.719108Z","caller":"traceutil/trace.go:171","msg":"trace[1589729030] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"322.321658ms","start":"2026-03-29T01:54:50.39676Z","end":"2026-03-29T01:54:50.719082Z","steps":["trace[1589729030] 'process raft request' (duration: 322.245476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.719194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:50.396753Z","time spent":"322.406061ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:50.719393Z","caller":"traceutil/trace.go:171","msg":"trace[587356582] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"323.368079ms","start":"2026-03-29T01:54:50.395762Z","end":"2026-03-29T01:54:50.71913Z","steps":["trace[587356582] 'process raft request' (duration: 117.894807ms)","trace[587356582] 'compare' (duration: 204.795743ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:50.719809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:50.395745Z","time spent":"323.968458ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:50.87301Z","caller":"traceutil/trace.go:171","msg":"trace[1501182516] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"144.622322ms","start":"2026-03-29T01:54:50.728362Z","end":"2026-03-29T01:54:50.872985Z","steps":["trace[1501182516] 'read index received' (duration: 103.097767ms)","trace[1501182516] 'applied index is now lower than readState.Index' (duration: 41.523395ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:50.873045Z","caller":"traceutil/trace.go:171","msg":"trace[1768243440] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"144.876429ms","start":"2026-03-29T01:54:50.72813Z","end":"2026-03-29T01:54:50.873006Z","steps":["trace[1768243440] 'process raft request' (duration: 103.426247ms)","trace[1768243440] 'compare' (duration: 41.269527ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:50.873275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.881339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t94j5\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-29T01:54:50.873304Z","caller":"traceutil/trace.go:171","msg":"trace[524803833] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t94j5; range_end:; response_count:1; response_revision:679; }","duration":"144.972902ms","start":"2026-03-29T01:54:50.728321Z","end":"2026-03-29T01:54:50.873294Z","steps":["trace[524803833] 'agreement among raft nodes before linearized reading' (duration: 144.762516ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:51.280019Z","caller":"traceutil/trace.go:171","msg":"trace[1357961462] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"388.931272ms","start":"2026-03-29T01:54:50.891062Z","end":"2026-03-29T01:54:51.279994Z","steps":["trace[1357961462] 'process raft request' (duration: 388.711186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:51.280147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:50.891028Z","time spent":"389.053686ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:53.193914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.511785ms","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-03-29T01:54:53.193962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.269548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:54:53.193993Z","caller":"traceutil/trace.go:171","msg":"trace[164540864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"469.33776ms","start":"2026-03-29T01:54:52.724648Z","end":"2026-03-29T01:54:53.193986Z","steps":["trace[164540864] 'range keys from in-memory index tree' (duration: 469.136674ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:53.193988Z","caller":"traceutil/trace.go:171","msg":"trace[399485806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"470.619598ms","start":"2026-03-29T01:54:52.723351Z","end":"2026-03-29T01:54:53.193971Z","steps":["trace[399485806] 'range keys from in-memory index tree' (duration: 470.328619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:53.194015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:52.724629Z","time spent":"469.380942ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:53.194021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:52.723331Z","time spent":"470.682789ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-29T01:54:53.194054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.861806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:54:53.194142Z","caller":"traceutil/trace.go:171","msg":"trace[2024357405] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:685; }","duration":"467.99145ms","start":"2026-03-29T01:54:52.726129Z","end":"2026-03-29T01:54:53.19412Z","steps":["trace[2024357405] 'count revisions from in-memory index tree' (duration: 467.753963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:53.194189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.08662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t94j5\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-03-29T01:54:53.19421Z","caller":"traceutil/trace.go:171","msg":"trace[1024851098] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t94j5; range_end:; response_count:1; response_revision:685; }","duration":"455.546033ms","start":"2026-03-29T01:54:52.738658Z","end":"2026-03-29T01:54:53.194204Z","steps":["trace[1024851098] 'range keys from in-memory index tree' (duration: 454.963026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:53.194205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:52.726118Z","time spent":"468.074362ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:54:53.194242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:52.738638Z","time spent":"455.590585ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17812,"request content":"key:\"/registry/pods/kube-system/cilium-t94j5\" "} {"level":"info","ts":"2026-03-29T01:54:53.874145Z","caller":"traceutil/trace.go:171","msg":"trace[1256475096] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"119.355203ms","start":"2026-03-29T01:54:53.754762Z","end":"2026-03-29T01:54:53.874117Z","steps":["trace[1256475096] 'process raft request' (duration: 119.145947ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:53.964415Z","caller":"traceutil/trace.go:171","msg":"trace[1023025832] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"129.83994ms","start":"2026-03-29T01:54:53.834554Z","end":"2026-03-29T01:54:53.964394Z","steps":["trace[1023025832] 'read index received' (duration: 39.54444ms)","trace[1023025832] 'applied index is now lower than readState.Index' (duration: 90.29471ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:53.964459Z","caller":"traceutil/trace.go:171","msg":"trace[1449767608] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"208.53495ms","start":"2026-03-29T01:54:53.75589Z","end":"2026-03-29T01:54:53.964425Z","steps":["trace[1449767608] 'process raft request' (duration: 206.618834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:53.965126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.53854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:53.965327Z","caller":"traceutil/trace.go:171","msg":"trace[2133210408] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:699; }","duration":"130.808908ms","start":"2026-03-29T01:54:53.834503Z","end":"2026-03-29T01:54:53.965312Z","steps":["trace[2133210408] 'agreement among raft nodes before linearized reading' (duration: 129.970874ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:54.999407Z","caller":"traceutil/trace.go:171","msg":"trace[1931681269] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"232.882473ms","start":"2026-03-29T01:54:54.766494Z","end":"2026-03-29T01:54:54.999377Z","steps":["trace[1931681269] 'process raft request' (duration: 232.735958ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:55.145575Z","caller":"traceutil/trace.go:171","msg":"trace[2119157114] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"309.187553ms","start":"2026-03-29T01:54:54.836365Z","end":"2026-03-29T01:54:55.145552Z","steps":["trace[2119157114] 'process raft request' (duration: 309.134372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:55.145975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:54.836343Z","time spent":"309.579465ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:55.145727Z","caller":"traceutil/trace.go:171","msg":"trace[286383652] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"364.622203ms","start":"2026-03-29T01:54:54.781073Z","end":"2026-03-29T01:54:55.145695Z","steps":["trace[286383652] 'process raft request' (duration: 363.306135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:55.146161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:54.78105Z","time spent":"365.030965ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18322,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:55.157253Z","caller":"traceutil/trace.go:171","msg":"trace[22330] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"121.014589ms","start":"2026-03-29T01:54:55.036222Z","end":"2026-03-29T01:54:55.157237Z","steps":["trace[22330] 'process raft request' (duration: 120.926926ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:55.561348Z","caller":"traceutil/trace.go:171","msg":"trace[2042952065] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"276.010777ms","start":"2026-03-29T01:54:55.285312Z","end":"2026-03-29T01:54:55.561322Z","steps":["trace[2042952065] 'process raft request' (duration: 275.851782ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:55.570398Z","caller":"traceutil/trace.go:171","msg":"trace[1084509082] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"130.527326ms","start":"2026-03-29T01:54:55.439841Z","end":"2026-03-29T01:54:55.570369Z","steps":["trace[1084509082] 'process raft request' (duration: 130.398132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.799065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410704349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:55:03.8987Z","caller":"traceutil/trace.go:171","msg":"trace[670358699] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"600.206297ms","start":"2026-03-29T01:55:03.29847Z","end":"2026-03-29T01:55:03.898676Z","steps":["trace[670358699] 'read index received' (duration: 599.95762ms)","trace[670358699] 'applied index is now lower than readState.Index' (duration: 247.697µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:03.898744Z","caller":"traceutil/trace.go:171","msg":"trace[1729576284] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"618.978484ms","start":"2026-03-29T01:55:03.279728Z","end":"2026-03-29T01:55:03.898706Z","steps":["trace[1729576284] 'process raft request' (duration: 618.779398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.898928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.114099ms","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-03-29T01:55:03.898968Z","caller":"traceutil/trace.go:171","msg":"trace[334128661] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:808; }","duration":"323.222422ms","start":"2026-03-29T01:55:03.575735Z","end":"2026-03-29T01:55:03.898958Z","steps":["trace[334128661] 'agreement among raft nodes before linearized reading' (duration: 323.074237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.899008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:03.575714Z","time spent":"323.284564ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:55:03.899037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.436548ms","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-03-29T01:55:03.899096Z","caller":"traceutil/trace.go:171","msg":"trace[1285217613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"287.52651ms","start":"2026-03-29T01:55:03.611556Z","end":"2026-03-29T01:55:03.899083Z","steps":["trace[1285217613] 'agreement among raft nodes before linearized reading' (duration: 287.416137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.89915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:03.27971Z","time spent":"619.213391ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:55:03.89959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.466803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:55:03.899733Z","caller":"traceutil/trace.go:171","msg":"trace[1312847664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"601.302667ms","start":"2026-03-29T01:55:03.298414Z","end":"2026-03-29T01:55:03.899717Z","steps":["trace[1312847664] 'agreement among raft nodes before linearized reading' (duration: 600.35834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.89989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:03.298391Z","time spent":"601.481723ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-29T01:55:06.907059Z","caller":"traceutil/trace.go:171","msg":"trace[1145235215] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"212.496097ms","start":"2026-03-29T01:55:06.694543Z","end":"2026-03-29T01:55:06.907039Z","steps":["trace[1145235215] 'process raft request' (duration: 211.87527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:07.256533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.485478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:55:07.256626Z","caller":"traceutil/trace.go:171","msg":"trace[1853228569] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"343.765324ms","start":"2026-03-29T01:55:06.912845Z","end":"2026-03-29T01:55:07.256611Z","steps":["trace[1853228569] 'process raft request' (duration: 241.152973ms)","trace[1853228569] 'compare' (duration: 102.179909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:07.256814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:06.912823Z","time spent":"343.971409ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:55:07.409789Z","caller":"traceutil/trace.go:171","msg":"trace[1183976118] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"150.068589ms","start":"2026-03-29T01:55:07.259685Z","end":"2026-03-29T01:55:07.409753Z","steps":["trace[1183976118] 'read index received' (duration: 149.538745ms)","trace[1183976118] 'applied index is now lower than readState.Index' (duration: 528.924µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:07.409914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.216383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:07.409947Z","caller":"traceutil/trace.go:171","msg":"trace[527226731] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:0; response_revision:825; }","duration":"150.300966ms","start":"2026-03-29T01:55:07.259635Z","end":"2026-03-29T01:55:07.409936Z","steps":["trace[527226731] 'agreement among raft nodes before linearized reading' (duration: 150.227613ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:07.410275Z","caller":"traceutil/trace.go:171","msg":"trace[1699102946] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"496.089014ms","start":"2026-03-29T01:55:06.914149Z","end":"2026-03-29T01:55:07.410238Z","steps":["trace[1699102946] 'process raft request' (duration: 495.181459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:07.410458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:06.914137Z","time spent":"496.227688ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:55:08.424203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410704396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:08.924675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410704396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:09.425278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410704396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:09.92583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198629410704396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:10.098649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.177218668s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:55:10.099163Z","caller":"traceutil/trace.go:171","msg":"trace[730181449] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"2.177788964s","start":"2026-03-29T01:55:07.921307Z","end":"2026-03-29T01:55:10.099096Z","steps":["trace[730181449] 'process raft request' (duration: 2.177604299s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.100206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:07.921285Z","time spent":"2.178816602s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:55:10.104406Z","caller":"traceutil/trace.go:171","msg":"trace[1859277077] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:860; }","duration":"2.180579378s","start":"2026-03-29T01:55:07.923814Z","end":"2026-03-29T01:55:10.104393Z","steps":["trace[1859277077] 'read index received' (duration: 2.175066909s)","trace[1859277077] 'applied index is now lower than readState.Index' (duration: 5.511749ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:10.104472Z","caller":"traceutil/trace.go:171","msg":"trace[482225567] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"1.685921803s","start":"2026-03-29T01:55:08.418543Z","end":"2026-03-29T01:55:10.104465Z","steps":["trace[482225567] 'process raft request' (duration: 1.685774949s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.104567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.418514Z","time spent":"1.685984964s","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:55:10.104673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.175008415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T01:55:10.104727Z","caller":"traceutil/trace.go:171","msg":"trace[766936952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"2.175083147s","start":"2026-03-29T01:55:07.929634Z","end":"2026-03-29T01:55:10.104718Z","steps":["trace[766936952] 'agreement among raft nodes before linearized reading' (duration: 2.174933403s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.104752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:07.929628Z","time spent":"2.175116838s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:55:10.104839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319033866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:55:10.104868Z","caller":"traceutil/trace.go:171","msg":"trace[1937417884] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:830; }","duration":"1.319102249s","start":"2026-03-29T01:55:08.78576Z","end":"2026-03-29T01:55:10.104862Z","steps":["trace[1937417884] 'agreement among raft nodes before linearized reading' (duration: 1.319010886s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.104888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.785742Z","time spent":"1.31913956s","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:55:10.104924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630197974s","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-03-29T01:55:10.10497Z","caller":"traceutil/trace.go:171","msg":"trace[1359674910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"1.630255454s","start":"2026-03-29T01:55:08.474688Z","end":"2026-03-29T01:55:10.104943Z","steps":["trace[1359674910] 'agreement among raft nodes before linearized reading' (duration: 1.630167671s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.104992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287564462s","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":"warn","ts":"2026-03-29T01:55:10.104996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.474666Z","time spent":"1.630321576s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-29T01:55:10.105014Z","caller":"traceutil/trace.go:171","msg":"trace[1666020903] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:830; }","duration":"1.287586104s","start":"2026-03-29T01:55:08.817419Z","end":"2026-03-29T01:55:10.105005Z","steps":["trace[1666020903] 'agreement among raft nodes before linearized reading' (duration: 1.287545993s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.105005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.181177146s","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-03-29T01:55:10.105039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.817352Z","time spent":"1.287680376s","remote":"127.0.0.1:40142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-29T01:55:10.105061Z","caller":"traceutil/trace.go:171","msg":"trace[735371629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"2.181259218s","start":"2026-03-29T01:55:07.923785Z","end":"2026-03-29T01:55:10.105044Z","steps":["trace[735371629] 'agreement among raft nodes before linearized reading' (duration: 2.181056032s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.105127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:07.923775Z","time spent":"2.18134325s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-29T01:55:10.1053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908766975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:10.105326Z","caller":"traceutil/trace.go:171","msg":"trace[1456348931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"1.908792575s","start":"2026-03-29T01:55:08.196527Z","end":"2026-03-29T01:55:10.105319Z","steps":["trace[1456348931] 'agreement among raft nodes before linearized reading' (duration: 1.908751994s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.105348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.196473Z","time spent":"1.908870687s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:55:10.105466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.018818927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-29T01:55:10.105528Z","caller":"traceutil/trace.go:171","msg":"trace[253208144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:830; }","duration":"2.01891278s","start":"2026-03-29T01:55:08.086604Z","end":"2026-03-29T01:55:10.105517Z","steps":["trace[253208144] 'agreement among raft nodes before linearized reading' (duration: 2.018364135s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:10.105581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.086588Z","time spent":"2.018983891s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:55:11.326838Z","caller":"traceutil/trace.go:171","msg":"trace[1821987274] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"130.445577ms","start":"2026-03-29T01:55:11.196365Z","end":"2026-03-29T01:55:11.326811Z","steps":["trace[1821987274] 'read index received' (duration: 130.18589ms)","trace[1821987274] 'applied index is now lower than readState.Index' (duration: 258.327µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:11.326939Z","caller":"traceutil/trace.go:171","msg":"trace[169803972] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"215.907825ms","start":"2026-03-29T01:55:11.110998Z","end":"2026-03-29T01:55:11.326906Z","steps":["trace[169803972] 'process raft request' (duration: 215.654508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:11.327074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.674253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:11.327165Z","caller":"traceutil/trace.go:171","msg":"trace[600919438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"130.832417ms","start":"2026-03-29T01:55:11.196309Z","end":"2026-03-29T01:55:11.327142Z","steps":["trace[600919438] 'agreement among raft nodes before linearized reading' (duration: 130.652372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:11.32731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.931379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:55:11.327439Z","caller":"traceutil/trace.go:171","msg":"trace[12495237] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:836; }","duration":"128.108854ms","start":"2026-03-29T01:55:11.199317Z","end":"2026-03-29T01:55:11.327426Z","steps":["trace[12495237] 'agreement among raft nodes before linearized reading' (duration: 127.648961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.526039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.706643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:55:12.526149Z","caller":"traceutil/trace.go:171","msg":"trace[1095608581] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"364.192284ms","start":"2026-03-29T01:55:12.16194Z","end":"2026-03-29T01:55:12.526132Z","steps":["trace[1095608581] 'process raft request' (duration: 84.297536ms)","trace[1095608581] 'compare' (duration: 279.564089ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:12.526208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.161911Z","time spent":"364.270426ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:55:12.563572Z","caller":"traceutil/trace.go:171","msg":"trace[1792100681] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"401.514612ms","start":"2026-03-29T01:55:12.162039Z","end":"2026-03-29T01:55:12.563554Z","steps":["trace[1792100681] 'process raft request' (duration: 401.385128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.563706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.16201Z","time spent":"401.618004ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:55:12.563859Z","caller":"traceutil/trace.go:171","msg":"trace[657464661] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:871; }","duration":"366.838115ms","start":"2026-03-29T01:55:12.196995Z","end":"2026-03-29T01:55:12.563833Z","steps":["trace[657464661] 'read index received' (duration: 49.23905ms)","trace[657464661] 'applied index is now lower than readState.Index' (duration: 317.597825ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:12.563938Z","caller":"traceutil/trace.go:171","msg":"trace[252921340] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"399.2211ms","start":"2026-03-29T01:55:12.16471Z","end":"2026-03-29T01:55:12.563931Z","steps":["trace[252921340] 'process raft request' (duration: 399.042215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.563981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.164691Z","time spent":"399.267171ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:55:12.564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.00791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:12.564036Z","caller":"traceutil/trace.go:171","msg":"trace[586806335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"367.091762ms","start":"2026-03-29T01:55:12.196932Z","end":"2026-03-29T01:55:12.564024Z","steps":["trace[586806335] 'agreement among raft nodes before linearized reading' (duration: 367.030721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.564096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.847037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x2hmx\" ","response":"range_response_count:1 size:2876"} {"level":"info","ts":"2026-03-29T01:55:12.564115Z","caller":"traceutil/trace.go:171","msg":"trace[1322817396] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x2hmx; range_end:; response_count:1; response_revision:842; }","duration":"175.900869ms","start":"2026-03-29T01:55:12.388207Z","end":"2026-03-29T01:55:12.564108Z","steps":["trace[1322817396] 'agreement among raft nodes before linearized reading' (duration: 175.823617ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:12.564122Z","caller":"traceutil/trace.go:171","msg":"trace[476281456] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"400.926585ms","start":"2026-03-29T01:55:12.163175Z","end":"2026-03-29T01:55:12.564101Z","steps":["trace[476281456] 'process raft request' (duration: 400.35076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.564225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.163146Z","time spent":"401.024138ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:55:12.564067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.196912Z","time spent":"367.147524ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:55:13.102215Z","caller":"traceutil/trace.go:171","msg":"trace[264748549] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"240.658734ms","start":"2026-03-29T01:55:12.861529Z","end":"2026-03-29T01:55:13.102188Z","steps":["trace[264748549] 'process raft request' (duration: 240.467949ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:47.734769Z","caller":"traceutil/trace.go:171","msg":"trace[366303842] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"118.897653ms","start":"2026-03-29T01:55:47.615814Z","end":"2026-03-29T01:55:47.734712Z","steps":["trace[366303842] 'process raft request' (duration: 118.73678ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:50.010413Z","caller":"traceutil/trace.go:171","msg":"trace[2054506812] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"252.027828ms","start":"2026-03-29T01:55:49.758362Z","end":"2026-03-29T01:55:50.01039Z","steps":["trace[2054506812] 'read index received' (duration: 251.803833ms)","trace[2054506812] 'applied index is now lower than readState.Index' (duration: 223.125µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:50.010525Z","caller":"traceutil/trace.go:171","msg":"trace[1586186094] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"270.322545ms","start":"2026-03-29T01:55:49.740169Z","end":"2026-03-29T01:55:50.010491Z","steps":["trace[1586186094] 'process raft request' (duration: 270.046878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:50.010595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.187403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:50.010639Z","caller":"traceutil/trace.go:171","msg":"trace[532153954] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1091; }","duration":"252.299856ms","start":"2026-03-29T01:55:49.758328Z","end":"2026-03-29T01:55:50.010628Z","steps":["trace[532153954] 'agreement among raft nodes before linearized reading' (duration: 252.156562ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:56.81667Z","caller":"traceutil/trace.go:171","msg":"trace[513063313] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"180.444103ms","start":"2026-03-29T01:55:56.636203Z","end":"2026-03-29T01:55:56.816647Z","steps":["trace[513063313] 'process raft request' (duration: 180.31313ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:23.122849Z","caller":"traceutil/trace.go:171","msg":"trace[260828035] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"143.689348ms","start":"2026-03-29T01:56:22.979124Z","end":"2026-03-29T01:56:23.122814Z","steps":["trace[260828035] 'process raft request' (duration: 143.522483ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:25.335191Z","caller":"traceutil/trace.go:171","msg":"trace[1204901416] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"137.60635ms","start":"2026-03-29T01:56:25.197566Z","end":"2026-03-29T01:56:25.335172Z","steps":["trace[1204901416] 'read index received' (duration: 137.396886ms)","trace[1204901416] 'applied index is now lower than readState.Index' (duration: 208.784µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:25.335242Z","caller":"traceutil/trace.go:171","msg":"trace[382660266] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"204.265356ms","start":"2026-03-29T01:56:25.130955Z","end":"2026-03-29T01:56:25.335221Z","steps":["trace[382660266] 'process raft request' (duration: 204.083253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:25.335333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.748083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:56:25.335364Z","caller":"traceutil/trace.go:171","msg":"trace[1992047468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"137.828795ms","start":"2026-03-29T01:56:25.197524Z","end":"2026-03-29T01:56:25.335353Z","steps":["trace[1992047468] 'agreement among raft nodes before linearized reading' (duration: 137.763154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:25.335865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.704186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-03-29T01:56:25.335864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.028618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-29T01:56:25.335893Z","caller":"traceutil/trace.go:171","msg":"trace[885868193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"128.745018ms","start":"2026-03-29T01:56:25.207141Z","end":"2026-03-29T01:56:25.335886Z","steps":["trace[885868193] 'agreement among raft nodes before linearized reading' (duration: 128.548003ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:25.335915Z","caller":"traceutil/trace.go:171","msg":"trace[611820511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"118.135829ms","start":"2026-03-29T01:56:25.217766Z","end":"2026-03-29T01:56:25.335902Z","steps":["trace[611820511] 'agreement among raft nodes before linearized reading' (duration: 117.972215ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:25.453452Z","caller":"traceutil/trace.go:171","msg":"trace[1916494800] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"112.999752ms","start":"2026-03-29T01:56:25.340425Z","end":"2026-03-29T01:56:25.453425Z","steps":["trace[1916494800] 'process raft request' (duration: 56.877784ms)","trace[1916494800] 'compare' (duration: 55.943854ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:25.45364Z","caller":"traceutil/trace.go:171","msg":"trace[1976794179] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"112.393661ms","start":"2026-03-29T01:56:25.341228Z","end":"2026-03-29T01:56:25.453621Z","steps":["trace[1976794179] 'process raft request' (duration: 112.33424ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:25.453845Z","caller":"traceutil/trace.go:171","msg":"trace[818558983] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"113.35305ms","start":"2026-03-29T01:56:25.340484Z","end":"2026-03-29T01:56:25.453837Z","steps":["trace[818558983] 'process raft request' (duration: 112.89614ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:55.058247Z","caller":"traceutil/trace.go:171","msg":"trace[45721725] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"392.709036ms","start":"2026-03-29T01:56:54.665496Z","end":"2026-03-29T01:56:55.058206Z","steps":["trace[45721725] 'read index received' (duration: 392.476912ms)","trace[45721725] 'applied index is now lower than readState.Index' (duration: 231.124µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:55.05827Z","caller":"traceutil/trace.go:171","msg":"trace[2142435403] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"430.010883ms","start":"2026-03-29T01:56:54.628229Z","end":"2026-03-29T01:56:55.05824Z","steps":["trace[2142435403] 'process raft request' (duration: 429.722268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:55.058373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.85305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:56:55.058432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:54.628203Z","time spent":"430.145637ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:56:55.058412Z","caller":"traceutil/trace.go:171","msg":"trace[2021952563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"392.937601ms","start":"2026-03-29T01:56:54.665463Z","end":"2026-03-29T01:56:55.0584Z","steps":["trace[2021952563] 'agreement among raft nodes before linearized reading' (duration: 392.857909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:55.058486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:54.66545Z","time spent":"393.027413ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:56:59.260267Z","caller":"traceutil/trace.go:171","msg":"trace[551340754] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"117.265054ms","start":"2026-03-29T01:56:59.142946Z","end":"2026-03-29T01:56:59.260211Z","steps":["trace[551340754] 'process raft request' (duration: 86.656622ms)","trace[551340754] 'compare' (duration: 30.372048ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:59.684562Z","caller":"traceutil/trace.go:171","msg":"trace[1809963148] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"152.395181ms","start":"2026-03-29T01:56:59.532142Z","end":"2026-03-29T01:56:59.684537Z","steps":["trace[1809963148] 'process raft request' (duration: 152.241818ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:02.142524Z","caller":"traceutil/trace.go:171","msg":"trace[1151180325] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"375.79919ms","start":"2026-03-29T01:57:01.766615Z","end":"2026-03-29T01:57:02.142414Z","steps":["trace[1151180325] 'read index received' (duration: 375.565656ms)","trace[1151180325] 'applied index is now lower than readState.Index' (duration: 229.784µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:02.142912Z","caller":"traceutil/trace.go:171","msg":"trace[137220016] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"449.465561ms","start":"2026-03-29T01:57:01.693422Z","end":"2026-03-29T01:57:02.142888Z","steps":["trace[137220016] 'process raft request' (duration: 448.822049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:02.143035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.693383Z","time spent":"449.570024ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:02.143404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.248939ms","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-03-29T01:57:02.143491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.875571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-29T01:57:02.143452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.185536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:57:02.143619Z","caller":"traceutil/trace.go:171","msg":"trace[1730689048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"363.376079ms","start":"2026-03-29T01:57:01.780233Z","end":"2026-03-29T01:57:02.143609Z","steps":["trace[1730689048] 'agreement among raft nodes before linearized reading' (duration: 363.110874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:02.14364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.780222Z","time spent":"363.412169ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:57:02.143581Z","caller":"traceutil/trace.go:171","msg":"trace[1025537947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"376.469473ms","start":"2026-03-29T01:57:01.767054Z","end":"2026-03-29T01:57:02.143524Z","steps":["trace[1025537947] 'agreement among raft nodes before linearized reading' (duration: 376.118177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:02.143738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.767024Z","time spent":"376.687027ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-29T01:57:02.143836Z","caller":"traceutil/trace.go:171","msg":"trace[1579452518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"376.969513ms","start":"2026-03-29T01:57:01.766561Z","end":"2026-03-29T01:57:02.14353Z","steps":["trace[1579452518] 'agreement among raft nodes before linearized reading' (duration: 376.85534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:02.143866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.766532Z","time spent":"377.327459ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:57:02.144028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.668878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:57:02.144095Z","caller":"traceutil/trace.go:171","msg":"trace[458974941] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1365; }","duration":"375.304881ms","start":"2026-03-29T01:57:01.768775Z","end":"2026-03-29T01:57:02.14408Z","steps":["trace[458974941] 'agreement among raft nodes before linearized reading' (duration: 374.631218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:02.144145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.768757Z","time spent":"375.368111ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-03-29T01:57:02.407437Z","caller":"traceutil/trace.go:171","msg":"trace[2146748390] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"259.293766ms","start":"2026-03-29T01:57:02.14812Z","end":"2026-03-29T01:57:02.407413Z","steps":["trace[2146748390] 'process raft request' (duration: 211.273404ms)","trace[2146748390] 'compare' (duration: 47.759757ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:03.438752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.547702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:57:03.438925Z","caller":"traceutil/trace.go:171","msg":"trace[278554720] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1422; }","duration":"1.242475252s","start":"2026-03-29T01:57:02.196404Z","end":"2026-03-29T01:57:03.438879Z","steps":["trace[278554720] 'read index received' (duration: 162.999947ms)","trace[278554720] 'applied index is now lower than readState.Index' (duration: 1.079474575s)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:03.438991Z","caller":"traceutil/trace.go:171","msg":"trace[783429173] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"1.290477224s","start":"2026-03-29T01:57:02.148505Z","end":"2026-03-29T01:57:03.438982Z","steps":["trace[783429173] 'process raft request' (duration: 1.080637417s)","trace[783429173] 'compare' (duration: 209.404119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:03.439045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:02.148493Z","time spent":"1.290522605s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:03.439263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242846109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.43939Z","caller":"traceutil/trace.go:171","msg":"trace[2075517708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"1.243002021s","start":"2026-03-29T01:57:02.196365Z","end":"2026-03-29T01:57:03.439367Z","steps":["trace[2075517708] 'agreement among raft nodes before linearized reading' (duration: 1.242807538s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:03.439324Z","caller":"traceutil/trace.go:171","msg":"trace[1759195282] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"1.29033422s","start":"2026-03-29T01:57:02.148977Z","end":"2026-03-29T01:57:03.439312Z","steps":["trace[1759195282] 'process raft request' (duration: 1.289847801s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.439651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:02.19635Z","time spent":"1.243182705s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:57:03.439686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:02.148969Z","time spent":"1.290621875s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:03.529708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.761617ms","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-03-29T01:57:03.529749Z","caller":"traceutil/trace.go:171","msg":"trace[176407676] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"369.785769ms","start":"2026-03-29T01:57:03.159932Z","end":"2026-03-29T01:57:03.529718Z","steps":["trace[176407676] 'process raft request' (duration: 369.389431ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:03.529787Z","caller":"traceutil/trace.go:171","msg":"trace[1136848844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1369; }","duration":"235.877699ms","start":"2026-03-29T01:57:03.293892Z","end":"2026-03-29T01:57:03.52977Z","steps":["trace[1136848844] 'agreement among raft nodes before linearized reading' (duration: 235.662484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.529722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.309369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.529852Z","caller":"traceutil/trace.go:171","msg":"trace[719428314] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1369; }","duration":"827.485242ms","start":"2026-03-29T01:57:02.702358Z","end":"2026-03-29T01:57:03.529843Z","steps":["trace[719428314] 'agreement among raft nodes before linearized reading' (duration: 827.320089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.529875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:02.702342Z","time spent":"827.526853ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-03-29T01:57:03.529878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:03.159905Z","time spent":"369.901722ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:03.529745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.340821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.530436Z","caller":"traceutil/trace.go:171","msg":"trace[46331212] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1369; }","duration":"255.071435ms","start":"2026-03-29T01:57:03.275333Z","end":"2026-03-29T01:57:03.530405Z","steps":["trace[46331212] 'agreement among raft nodes before linearized reading' (duration: 254.324951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.530302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.930314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.53195Z","caller":"traceutil/trace.go:171","msg":"trace[644312169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1369; }","duration":"540.637124ms","start":"2026-03-29T01:57:02.991289Z","end":"2026-03-29T01:57:03.531926Z","steps":["trace[644312169] 'agreement among raft nodes before linearized reading' (duration: 538.888682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.532129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:02.991273Z","time spent":"540.832289ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-03-29T01:57:03.73239Z","caller":"traceutil/trace.go:171","msg":"trace[1139177519] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"197.231233ms","start":"2026-03-29T01:57:03.535138Z","end":"2026-03-29T01:57:03.732369Z","steps":["trace[1139177519] 'read index received' (duration: 173.477103ms)","trace[1139177519] 'applied index is now lower than readState.Index' (duration: 23.75316ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:03.73267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.498479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.732767Z","caller":"traceutil/trace.go:171","msg":"trace[379777209] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1370; }","duration":"197.640291ms","start":"2026-03-29T01:57:03.535105Z","end":"2026-03-29T01:57:03.732745Z","steps":["trace[379777209] 'agreement among raft nodes before linearized reading' (duration: 197.431777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:03.732736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.035914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:03.732763Z","caller":"traceutil/trace.go:171","msg":"trace[571053676] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"197.993068ms","start":"2026-03-29T01:57:03.534744Z","end":"2026-03-29T01:57:03.732737Z","steps":["trace[571053676] 'process raft request' (duration: 173.82294ms)","trace[571053676] 'compare' (duration: 23.447945ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:03.73299Z","caller":"traceutil/trace.go:171","msg":"trace[1061435293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"194.278638ms","start":"2026-03-29T01:57:03.538667Z","end":"2026-03-29T01:57:03.732946Z","steps":["trace[1061435293] 'agreement among raft nodes before linearized reading' (duration: 194.017523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.141622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.771661ms","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-03-29T01:57:05.141902Z","caller":"traceutil/trace.go:171","msg":"trace[1931361330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"995.083047ms","start":"2026-03-29T01:57:04.1468Z","end":"2026-03-29T01:57:05.141883Z","steps":["trace[1931361330] 'range keys from in-memory index tree' (duration: 994.634269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.14202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:04.146787Z","time spent":"995.21867ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:57:05.141698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.236332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-29T01:57:05.142142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.897607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-29T01:57:05.142196Z","caller":"traceutil/trace.go:171","msg":"trace[1392245243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"730.983689ms","start":"2026-03-29T01:57:04.411196Z","end":"2026-03-29T01:57:05.14218Z","steps":["trace[1392245243] 'range keys from in-memory index tree' (duration: 730.767125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.14225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:04.411177Z","time spent":"731.06253ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:57:05.142192Z","caller":"traceutil/trace.go:171","msg":"trace[1852181670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1370; }","duration":"876.726241ms","start":"2026-03-29T01:57:04.265427Z","end":"2026-03-29T01:57:05.142153Z","steps":["trace[1852181670] 'count revisions from in-memory index tree' (duration: 876.157541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.142358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:04.26537Z","time spent":"876.959245ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:57:05.1418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.870103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:05.142487Z","caller":"traceutil/trace.go:171","msg":"trace[792611010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"477.602837ms","start":"2026-03-29T01:57:04.664856Z","end":"2026-03-29T01:57:05.142459Z","steps":["trace[792611010] 'range keys from in-memory index tree' (duration: 476.772922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.142571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:04.664836Z","time spent":"477.708789ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:57:05.38048Z","caller":"traceutil/trace.go:171","msg":"trace[603270539] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"185.445527ms","start":"2026-03-29T01:57:05.195012Z","end":"2026-03-29T01:57:05.380458Z","steps":["trace[603270539] 'read index received' (duration: 93.445261ms)","trace[603270539] 'applied index is now lower than readState.Index' (duration: 91.999296ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:05.380631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.599899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:05.380662Z","caller":"traceutil/trace.go:171","msg":"trace[1448031451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"185.668931ms","start":"2026-03-29T01:57:05.194984Z","end":"2026-03-29T01:57:05.380653Z","steps":["trace[1448031451] 'agreement among raft nodes before linearized reading' (duration: 185.59839ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:05.380587Z","caller":"traceutil/trace.go:171","msg":"trace[245783925] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"233.37188ms","start":"2026-03-29T01:57:05.14718Z","end":"2026-03-29T01:57:05.380551Z","steps":["trace[245783925] 'process raft request' (duration: 141.282554ms)","trace[245783925] 'compare' (duration: 91.891793ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:05.380721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.259528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:05.380804Z","caller":"traceutil/trace.go:171","msg":"trace[1173070242] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1372; }","duration":"157.399201ms","start":"2026-03-29T01:57:05.223386Z","end":"2026-03-29T01:57:05.380785Z","steps":["trace[1173070242] 'agreement among raft nodes before linearized reading' (duration: 157.250148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:05.381048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.128928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:57:05.381086Z","caller":"traceutil/trace.go:171","msg":"trace[468698463] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1372; }","duration":"104.22871ms","start":"2026-03-29T01:57:05.276848Z","end":"2026-03-29T01:57:05.381076Z","steps":["trace[468698463] 'agreement among raft nodes before linearized reading' (duration: 104.160559ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:06.341218Z","caller":"traceutil/trace.go:171","msg":"trace[1242490416] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"103.167447ms","start":"2026-03-29T01:57:06.238031Z","end":"2026-03-29T01:57:06.341198Z","steps":["trace[1242490416] 'process raft request' (duration: 103.043995ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:07.7341Z","caller":"traceutil/trace.go:171","msg":"trace[1300331769] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"151.391056ms","start":"2026-03-29T01:57:07.582684Z","end":"2026-03-29T01:57:07.734075Z","steps":["trace[1300331769] 'process raft request' (duration: 150.880026ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:09.642445Z","caller":"traceutil/trace.go:171","msg":"trace[1187567761] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"137.835825ms","start":"2026-03-29T01:57:09.504574Z","end":"2026-03-29T01:57:09.64241Z","steps":["trace[1187567761] 'process raft request' (duration: 117.908001ms)","trace[1187567761] 'compare' (duration: 19.579187ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:11.581136Z","caller":"traceutil/trace.go:171","msg":"trace[1831429717] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"107.233357ms","start":"2026-03-29T01:57:11.473853Z","end":"2026-03-29T01:57:11.581087Z","steps":["trace[1831429717] 'process raft request' (duration: 107.082854ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:11.991907Z","caller":"traceutil/trace.go:171","msg":"trace[1740116477] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"104.392654ms","start":"2026-03-29T01:57:11.887495Z","end":"2026-03-29T01:57:11.991888Z","steps":["trace[1740116477] 'process raft request' (duration: 104.245382ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:15.844318Z","caller":"traceutil/trace.go:171","msg":"trace[1151337996] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"159.768481ms","start":"2026-03-29T01:57:15.684528Z","end":"2026-03-29T01:57:15.844296Z","steps":["trace[1151337996] 'read index received' (duration: 159.70924ms)","trace[1151337996] 'applied index is now lower than readState.Index' (duration: 58.311µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:15.844424Z","caller":"traceutil/trace.go:171","msg":"trace[1645862013] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"173.205613ms","start":"2026-03-29T01:57:15.671192Z","end":"2026-03-29T01:57:15.844398Z","steps":["trace[1645862013] 'process raft request' (duration: 172.964698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:15.844598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.019007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" ","response":"range_response_count:1 size:469"} {"level":"info","ts":"2026-03-29T01:57:15.844644Z","caller":"traceutil/trace.go:171","msg":"trace[1232310748] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1409; }","duration":"160.123379ms","start":"2026-03-29T01:57:15.684511Z","end":"2026-03-29T01:57:15.844634Z","steps":["trace[1232310748] 'agreement among raft nodes before linearized reading' (duration: 159.900305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:15.849479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.710967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-29T01:57:15.849536Z","caller":"traceutil/trace.go:171","msg":"trace[1428893500] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:1410; }","duration":"107.799758ms","start":"2026-03-29T01:57:15.741725Z","end":"2026-03-29T01:57:15.849524Z","steps":["trace[1428893500] 'agreement among raft nodes before linearized reading' (duration: 107.681935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:16.74659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.781943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-29T01:57:16.746703Z","caller":"traceutil/trace.go:171","msg":"trace[1357309360] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"125.720864ms","start":"2026-03-29T01:57:16.62096Z","end":"2026-03-29T01:57:16.74668Z","steps":["trace[1357309360] 'read index received' (duration: 17.763008ms)","trace[1357309360] 'applied index is now lower than readState.Index' (duration: 107.956036ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:16.746837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.867087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-03-29T01:57:16.746881Z","caller":"traceutil/trace.go:171","msg":"trace[366902625] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1413; }","duration":"125.940298ms","start":"2026-03-29T01:57:16.620923Z","end":"2026-03-29T01:57:16.746863Z","steps":["trace[366902625] 'agreement among raft nodes before linearized reading' (duration: 125.846457ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:17.574014Z","caller":"traceutil/trace.go:171","msg":"trace[2047506774] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"115.823291ms","start":"2026-03-29T01:57:17.458174Z","end":"2026-03-29T01:57:17.573997Z","steps":["trace[2047506774] 'read index received' (duration: 115.731769ms)","trace[2047506774] 'applied index is now lower than readState.Index' (duration: 90.782µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:17.574088Z","caller":"traceutil/trace.go:171","msg":"trace[296143049] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"137.150863ms","start":"2026-03-29T01:57:17.436914Z","end":"2026-03-29T01:57:17.574065Z","steps":["trace[296143049] 'process raft request' (duration: 136.98738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:17.574261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.017484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-03-29T01:57:17.574439Z","caller":"traceutil/trace.go:171","msg":"trace[1775367397] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1414; }","duration":"116.285689ms","start":"2026-03-29T01:57:17.458129Z","end":"2026-03-29T01:57:17.574415Z","steps":["trace[1775367397] 'agreement among raft nodes before linearized reading' (duration: 115.956064ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:19.019543Z","caller":"traceutil/trace.go:171","msg":"trace[817646887] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"266.839851ms","start":"2026-03-29T01:57:18.752676Z","end":"2026-03-29T01:57:19.019516Z","steps":["trace[817646887] 'read index received' (duration: 266.574646ms)","trace[817646887] 'applied index is now lower than readState.Index' (duration: 263.795µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:19.019761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.070745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:638"} {"level":"info","ts":"2026-03-29T01:57:19.019814Z","caller":"traceutil/trace.go:171","msg":"trace[1720135607] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:1420; }","duration":"267.169437ms","start":"2026-03-29T01:57:18.752633Z","end":"2026-03-29T01:57:19.019802Z","steps":["trace[1720135607] 'agreement among raft nodes before linearized reading' (duration: 267.019634ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:19.019699Z","caller":"traceutil/trace.go:171","msg":"trace[970315730] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"270.738521ms","start":"2026-03-29T01:57:18.748922Z","end":"2026-03-29T01:57:19.01966Z","steps":["trace[970315730] 'process raft request' (duration: 270.423796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:19.019842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.265767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-03-29T01:57:19.020129Z","caller":"traceutil/trace.go:171","msg":"trace[1251833625] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1420; }","duration":"212.593383ms","start":"2026-03-29T01:57:18.8075Z","end":"2026-03-29T01:57:19.020094Z","steps":["trace[1251833625] 'agreement among raft nodes before linearized reading' (duration: 212.240587ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:24.154897Z","caller":"traceutil/trace.go:171","msg":"trace[1581406252] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"127.147997ms","start":"2026-03-29T01:57:24.027718Z","end":"2026-03-29T01:57:24.154866Z","steps":["trace[1581406252] 'read index received' (duration: 111.901129ms)","trace[1581406252] 'applied index is now lower than readState.Index' (duration: 15.245568ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:24.154961Z","caller":"traceutil/trace.go:171","msg":"trace[1835960403] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"131.123637ms","start":"2026-03-29T01:57:24.023802Z","end":"2026-03-29T01:57:24.154925Z","steps":["trace[1835960403] 'process raft request' (duration: 115.836059ms)","trace[1835960403] 'compare' (duration: 15.090255ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:24.155278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.532244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-29T01:57:24.155346Z","caller":"traceutil/trace.go:171","msg":"trace[940396490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1437; }","duration":"127.695066ms","start":"2026-03-29T01:57:24.027634Z","end":"2026-03-29T01:57:24.155329Z","steps":["trace[940396490] 'agreement among raft nodes before linearized reading' (duration: 127.402281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:24.155444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.626758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-29T01:57:24.155519Z","caller":"traceutil/trace.go:171","msg":"trace[640653008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1437; }","duration":"126.76071ms","start":"2026-03-29T01:57:24.028743Z","end":"2026-03-29T01:57:24.155504Z","steps":["trace[640653008] 'agreement among raft nodes before linearized reading' (duration: 126.337423ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:27.736475Z","caller":"traceutil/trace.go:171","msg":"trace[1007392218] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1508; }","duration":"271.777869ms","start":"2026-03-29T01:57:27.464674Z","end":"2026-03-29T01:57:27.736452Z","steps":["trace[1007392218] 'read index received' (duration: 271.486134ms)","trace[1007392218] 'applied index is now lower than readState.Index' (duration: 290.395µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:27.736542Z","caller":"traceutil/trace.go:171","msg":"trace[655301818] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"331.387631ms","start":"2026-03-29T01:57:27.405123Z","end":"2026-03-29T01:57:27.736511Z","steps":["trace[655301818] 'process raft request' (duration: 331.132576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:27.73666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.275041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-public/\" range_end:\"/registry/networkpolicies/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:57:27.736685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:27.405082Z","time spent":"331.528993ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:27.736727Z","caller":"traceutil/trace.go:171","msg":"trace[2046301784] range","detail":"{range_begin:/registry/networkpolicies/kube-public/; range_end:/registry/networkpolicies/kube-public0; response_count:0; response_revision:1447; }","duration":"255.396022ms","start":"2026-03-29T01:57:27.481315Z","end":"2026-03-29T01:57:27.736711Z","steps":["trace[2046301784] 'agreement among raft nodes before linearized reading' (duration: 255.27586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:27.736761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.348169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"warn","ts":"2026-03-29T01:57:27.736649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.937861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:27.736798Z","caller":"traceutil/trace.go:171","msg":"trace[158276997] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:1447; }","duration":"252.40182ms","start":"2026-03-29T01:57:27.484379Z","end":"2026-03-29T01:57:27.736781Z","steps":["trace[158276997] 'agreement among raft nodes before linearized reading' (duration: 252.321949ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:27.736828Z","caller":"traceutil/trace.go:171","msg":"trace[787617650] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1447; }","duration":"272.149675ms","start":"2026-03-29T01:57:27.46466Z","end":"2026-03-29T01:57:27.73681Z","steps":["trace[787617650] 'agreement among raft nodes before linearized reading' (duration: 271.87993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:27.736811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.581982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4637"} {"level":"info","ts":"2026-03-29T01:57:27.73688Z","caller":"traceutil/trace.go:171","msg":"trace[1213470792] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1447; }","duration":"209.695435ms","start":"2026-03-29T01:57:27.527168Z","end":"2026-03-29T01:57:27.736864Z","steps":["trace[1213470792] 'agreement among raft nodes before linearized reading' (duration: 209.482441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:29.605482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.691961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-03-29T01:57:29.605569Z","caller":"traceutil/trace.go:171","msg":"trace[181216897] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1455; }","duration":"108.819383ms","start":"2026-03-29T01:57:29.496729Z","end":"2026-03-29T01:57:29.605549Z","steps":["trace[181216897] 'range keys from in-memory index tree' (duration: 108.61592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.369998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.395305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:57:30.369984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.617887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-03-29T01:57:30.370123Z","caller":"traceutil/trace.go:171","msg":"trace[1029715500] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1457; }","duration":"100.82327ms","start":"2026-03-29T01:57:30.269279Z","end":"2026-03-29T01:57:30.370102Z","steps":["trace[1029715500] 'range keys from in-memory index tree' (duration: 100.432163ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.37003Z","caller":"traceutil/trace.go:171","msg":"trace[1712135672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1457; }","duration":"175.444375ms","start":"2026-03-29T01:57:30.194575Z","end":"2026-03-29T01:57:30.37002Z","steps":["trace[1712135672] 'range keys from in-memory index tree' (duration: 175.347324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.370012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.80517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-03-29T01:57:30.370287Z","caller":"traceutil/trace.go:171","msg":"trace[56652574] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1457; }","duration":"135.115354ms","start":"2026-03-29T01:57:30.235147Z","end":"2026-03-29T01:57:30.370263Z","steps":["trace[56652574] 'range keys from in-memory index tree' (duration: 134.637317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.37022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.838132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/local-path-storage/\" range_end:\"/registry/roles/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:30.370406Z","caller":"traceutil/trace.go:171","msg":"trace[733349705] range","detail":"{range_begin:/registry/roles/local-path-storage/; range_end:/registry/roles/local-path-storage0; response_count:0; response_revision:1457; }","duration":"142.145388ms","start":"2026-03-29T01:57:30.228242Z","end":"2026-03-29T01:57:30.370387Z","steps":["trace[733349705] 'range keys from in-memory index tree' (duration: 141.766881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.369931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.567321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-03-29T01:57:30.370469Z","caller":"traceutil/trace.go:171","msg":"trace[913395904] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1457; }","duration":"129.147042ms","start":"2026-03-29T01:57:30.241316Z","end":"2026-03-29T01:57:30.370463Z","steps":["trace[913395904] 'range keys from in-memory index tree' (duration: 128.50582ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.428435Z","caller":"traceutil/trace.go:171","msg":"trace[391032762] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"153.010196ms","start":"2026-03-29T01:57:30.275404Z","end":"2026-03-29T01:57:30.428414Z","steps":["trace[391032762] 'read index received' (duration: 152.795131ms)","trace[391032762] 'applied index is now lower than readState.Index' (duration: 213.925µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:30.428534Z","caller":"traceutil/trace.go:171","msg":"trace[942845601] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"158.742325ms","start":"2026-03-29T01:57:30.269764Z","end":"2026-03-29T01:57:30.428507Z","steps":["trace[942845601] 'process raft request' (duration: 158.511952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.428654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.067185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-29T01:57:30.428662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.24692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-29T01:57:30.428697Z","caller":"traceutil/trace.go:171","msg":"trace[1543489832] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1458; }","duration":"131.161126ms","start":"2026-03-29T01:57:30.29752Z","end":"2026-03-29T01:57:30.428681Z","steps":["trace[1543489832] 'agreement among raft nodes before linearized reading' (duration: 131.057345ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.428704Z","caller":"traceutil/trace.go:171","msg":"trace[1148248135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1458; }","duration":"153.326182ms","start":"2026-03-29T01:57:30.275365Z","end":"2026-03-29T01:57:30.428691Z","steps":["trace[1148248135] 'agreement among raft nodes before linearized reading' (duration: 153.173239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.428864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.218506ms","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-03-29T01:57:30.42889Z","caller":"traceutil/trace.go:171","msg":"trace[1968789804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1458; }","duration":"120.278298ms","start":"2026-03-29T01:57:30.308604Z","end":"2026-03-29T01:57:30.428882Z","steps":["trace[1968789804] 'agreement among raft nodes before linearized reading' (duration: 120.204157ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:32.22964Z","caller":"traceutil/trace.go:171","msg":"trace[30607857] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"102.367929ms","start":"2026-03-29T01:57:32.127242Z","end":"2026-03-29T01:57:32.22961Z","steps":["trace[30607857] 'process raft request' (duration: 102.120624ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:36.42679Z","caller":"traceutil/trace.go:171","msg":"trace[31347497] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"119.228602ms","start":"2026-03-29T01:57:36.307535Z","end":"2026-03-29T01:57:36.426763Z","steps":["trace[31347497] 'process raft request' (duration: 119.019239ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:40.766377Z","caller":"traceutil/trace.go:171","msg":"trace[2081084790] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"128.039515ms","start":"2026-03-29T01:57:40.638308Z","end":"2026-03-29T01:57:40.766348Z","steps":["trace[2081084790] 'process raft request' (duration: 76.070872ms)","trace[2081084790] 'compare' (duration: 51.848871ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:40.781909Z","caller":"traceutil/trace.go:171","msg":"trace[188510278] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1555; }","duration":"135.266457ms","start":"2026-03-29T01:57:40.646614Z","end":"2026-03-29T01:57:40.781881Z","steps":["trace[188510278] 'read index received' (duration: 67.822222ms)","trace[188510278] 'applied index is now lower than readState.Index' (duration: 67.443115ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:40.782009Z","caller":"traceutil/trace.go:171","msg":"trace[1102516419] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"141.232038ms","start":"2026-03-29T01:57:40.640751Z","end":"2026-03-29T01:57:40.781983Z","steps":["trace[1102516419] 'process raft request' (duration: 140.866282ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:40.781995Z","caller":"traceutil/trace.go:171","msg":"trace[359067960] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"141.055226ms","start":"2026-03-29T01:57:40.640902Z","end":"2026-03-29T01:57:40.781957Z","steps":["trace[359067960] 'process raft request' (duration: 140.934364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:40.782094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.453311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gss6l\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-03-29T01:57:40.782136Z","caller":"traceutil/trace.go:171","msg":"trace[1341420106] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-gss6l; range_end:; response_count:1; response_revision:1494; }","duration":"135.538522ms","start":"2026-03-29T01:57:40.646584Z","end":"2026-03-29T01:57:40.782123Z","steps":["trace[1341420106] 'agreement among raft nodes before linearized reading' (duration: 135.390679ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:42.876287Z","caller":"traceutil/trace.go:171","msg":"trace[1167066126] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"103.318868ms","start":"2026-03-29T01:57:42.772943Z","end":"2026-03-29T01:57:42.876262Z","steps":["trace[1167066126] 'process raft request' (duration: 102.677777ms)"],"step_count":1}