{"level":"warn","ts":"2026-07-01T07:24:57.428079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:24:57.428184Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:24:57.428261Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:24:57.428273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-01T07:24:57.428293Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:24:57.428904Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-01T07:24:57.428972Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:24:57.43259Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.406485ms"} {"level":"info","ts":"2026-07-01T07:24:57.440834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-01T07:24:57.440914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:24:57.440936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-01T07:24:57.440947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:24:57.440959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-01T07:24:57.440996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-01T07:24:57.447491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:24:57.449137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:24:57.450384Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T07:24:57.454088Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:24:57.454255Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:24:57.454388Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:24:57.454419Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:24:57.454589Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:24:57.457365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-01T07:24:57.457743Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-01T07:24:57.460708Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:24:57.460847Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-01T07:24:57.460884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-01T07:24:57.460945Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:24:57.46098Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:24:57.641869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:24:57.641929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:24:57.641966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-01T07:24:57.641981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:24:57.641989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-01T07:24:57.642019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-01T07:24:57.642029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-01T07:24:57.642958Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:24:57.643827Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:24:57.64403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:24:57.644092Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:24:57.644938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:24:57.645054Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:24:57.645081Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:24:57.646617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-07-01T07:24:57.64788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:24:57.654402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:24:57.65442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-07-01T07:25:34.896704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.240154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:25:34.896896Z","caller":"traceutil/trace.go:171","msg":"trace[983994987] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"322.808144ms","start":"2026-07-01T07:25:34.574058Z","end":"2026-07-01T07:25:34.896866Z","steps":["trace[983994987] 'process raft request' (duration: 47.332533ms)","trace[983994987] 'compare' (duration: 271.117551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:34.897015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:34.574046Z","time spent":"322.904476ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:25:34.916238Z","caller":"traceutil/trace.go:171","msg":"trace[1827842466] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"341.809204ms","start":"2026-07-01T07:25:34.574398Z","end":"2026-07-01T07:25:34.916208Z","steps":["trace[1827842466] 'process raft request' (duration: 341.551997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:34.916415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:34.57439Z","time spent":"341.97032ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:25:34.917079Z","caller":"traceutil/trace.go:171","msg":"trace[322661533] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"340.918609ms","start":"2026-07-01T07:25:34.576133Z","end":"2026-07-01T07:25:34.917052Z","steps":["trace[322661533] 'process raft request' (duration: 340.000994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:34.917667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:34.576123Z","time spent":"341.447445ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:25:34.922919Z","caller":"traceutil/trace.go:171","msg":"trace[1710277799] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"160.000441ms","start":"2026-07-01T07:25:34.762903Z","end":"2026-07-01T07:25:34.922903Z","steps":["trace[1710277799] 'process raft request' (duration: 159.94562ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:35.818396Z","caller":"traceutil/trace.go:171","msg":"trace[836419210] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"110.952276ms","start":"2026-07-01T07:25:35.707411Z","end":"2026-07-01T07:25:35.818363Z","steps":["trace[836419210] 'process raft request' (duration: 96.16729ms)","trace[836419210] 'compare' (duration: 14.665563ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:35.819868Z","caller":"traceutil/trace.go:171","msg":"trace[23145116] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"111.562522ms","start":"2026-07-01T07:25:35.708257Z","end":"2026-07-01T07:25:35.81982Z","steps":["trace[23145116] 'process raft request' (duration: 111.325686ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:36.08949Z","caller":"traceutil/trace.go:171","msg":"trace[773056951] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"115.653134ms","start":"2026-07-01T07:25:35.97382Z","end":"2026-07-01T07:25:36.089473Z","steps":["trace[773056951] 'process raft request' (duration: 115.555252ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:41.943943Z","caller":"traceutil/trace.go:171","msg":"trace[412114741] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:717; }","duration":"100.597192ms","start":"2026-07-01T07:25:41.843328Z","end":"2026-07-01T07:25:41.943926Z","steps":["trace[412114741] 'read index received' (duration: 97.686732ms)","trace[412114741] 'applied index is now lower than readState.Index' (duration: 2.90973ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:41.943995Z","caller":"traceutil/trace.go:171","msg":"trace[1062609628] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"101.230909ms","start":"2026-07-01T07:25:41.842733Z","end":"2026-07-01T07:25:41.943964Z","steps":["trace[1062609628] 'process raft request' (duration: 101.134086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:41.944414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.026444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T07:25:41.944523Z","caller":"traceutil/trace.go:171","msg":"trace[1251917183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"101.191147ms","start":"2026-07-01T07:25:41.843301Z","end":"2026-07-01T07:25:41.944492Z","steps":["trace[1251917183] 'agreement among raft nodes before linearized reading' (duration: 100.745605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.002742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.106713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:43.002825Z","caller":"traceutil/trace.go:171","msg":"trace[1836858167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"431.231116ms","start":"2026-07-01T07:25:42.571575Z","end":"2026-07-01T07:25:43.002806Z","steps":["trace[1836858167] 'range keys from in-memory index tree' (duration: 430.99327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.002862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:42.571556Z","time spent":"431.298349ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:25:47.778173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.073804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:47.778331Z","caller":"traceutil/trace.go:171","msg":"trace[2051875466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"207.308361ms","start":"2026-07-01T07:25:47.570995Z","end":"2026-07-01T07:25:47.778303Z","steps":["trace[2051875466] 'range keys from in-memory index tree' (duration: 206.95324ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:32.110755Z","caller":"traceutil/trace.go:171","msg":"trace[2002029170] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"235.69069ms","start":"2026-07-01T07:26:31.875041Z","end":"2026-07-01T07:26:32.110732Z","steps":["trace[2002029170] 'process raft request' (duration: 235.571206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:32.496995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.470127ms","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-07-01T07:26:32.497086Z","caller":"traceutil/trace.go:171","msg":"trace[1077783819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"308.612561ms","start":"2026-07-01T07:26:32.188457Z","end":"2026-07-01T07:26:32.497069Z","steps":["trace[1077783819] 'range keys from in-memory index tree' (duration: 308.21603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:32.497127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.095269ms","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-07-01T07:26:32.497136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:32.188436Z","time spent":"308.691513ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:26:32.497101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.180611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:26:32.497176Z","caller":"traceutil/trace.go:171","msg":"trace[301149130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"307.181992ms","start":"2026-07-01T07:26:32.189982Z","end":"2026-07-01T07:26:32.497164Z","steps":["trace[301149130] 'range keys from in-memory index tree' (duration: 306.993546ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:32.497201Z","caller":"traceutil/trace.go:171","msg":"trace[1240148103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"315.325175ms","start":"2026-07-01T07:26:32.181854Z","end":"2026-07-01T07:26:32.497179Z","steps":["trace[1240148103] 'range keys from in-memory index tree' (duration: 315.025816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:32.497211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:32.189966Z","time spent":"307.237353ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:26:32.497247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:32.181834Z","time spent":"315.403508ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:26:32.497321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.289737ms","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-07-01T07:26:32.497426Z","caller":"traceutil/trace.go:171","msg":"trace[1174609545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"306.423041ms","start":"2026-07-01T07:26:32.190973Z","end":"2026-07-01T07:26:32.497396Z","steps":["trace[1174609545] 'range keys from in-memory index tree' (duration: 306.117482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:32.49748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:32.190965Z","time spent":"306.504893ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T07:26:54.984629Z","caller":"traceutil/trace.go:171","msg":"trace[960792825] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"385.794513ms","start":"2026-07-01T07:26:54.598801Z","end":"2026-07-01T07:26:54.984595Z","steps":["trace[960792825] 'read index received' (duration: 385.596228ms)","trace[960792825] 'applied index is now lower than readState.Index' (duration: 196.995µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:54.984661Z","caller":"traceutil/trace.go:171","msg":"trace[671040488] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"417.772905ms","start":"2026-07-01T07:26:54.566864Z","end":"2026-07-01T07:26:54.984637Z","steps":["trace[671040488] 'process raft request' (duration: 417.555269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:54.984843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.566837Z","time spent":"417.882788ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:26:54.984865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.395264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:26:54.98488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.062521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:26:54.984933Z","caller":"traceutil/trace.go:171","msg":"trace[1381131223] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1098; }","duration":"279.491477ms","start":"2026-07-01T07:26:54.705424Z","end":"2026-07-01T07:26:54.984915Z","steps":["trace[1381131223] 'agreement among raft nodes before linearized reading' (duration: 279.350153ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:54.984944Z","caller":"traceutil/trace.go:171","msg":"trace[575325767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"386.186824ms","start":"2026-07-01T07:26:54.598742Z","end":"2026-07-01T07:26:54.984929Z","steps":["trace[575325767] 'agreement among raft nodes before linearized reading' (duration: 385.966688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:54.98498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.598719Z","time spent":"386.256576ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:26:54.984998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.435159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-01T07:26:54.985033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.435868ms","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-07-01T07:26:54.985079Z","caller":"traceutil/trace.go:171","msg":"trace[1841206113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"332.541043ms","start":"2026-07-01T07:26:54.652521Z","end":"2026-07-01T07:26:54.985062Z","steps":["trace[1841206113] 'agreement among raft nodes before linearized reading' (duration: 332.360658ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:54.985104Z","caller":"traceutil/trace.go:171","msg":"trace[1981429108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"325.535271ms","start":"2026-07-01T07:26:54.65955Z","end":"2026-07-01T07:26:54.985085Z","steps":["trace[1981429108] 'agreement among raft nodes before linearized reading' (duration: 325.322416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:54.985118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.652511Z","time spent":"332.599464ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T07:26:54.985149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.659538Z","time spent":"325.599373ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:26:54.98503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.801509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T07:26:54.985268Z","caller":"traceutil/trace.go:171","msg":"trace[702282324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"337.080206ms","start":"2026-07-01T07:26:54.648175Z","end":"2026-07-01T07:26:54.985256Z","steps":["trace[702282324] 'agreement among raft nodes before linearized reading' (duration: 336.581162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:54.985311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.648155Z","time spent":"337.141118ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:26:55.554812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.289845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:26:55.554978Z","caller":"traceutil/trace.go:171","msg":"trace[1029726045] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"565.42441ms","start":"2026-07-01T07:26:54.989529Z","end":"2026-07-01T07:26:55.554953Z","steps":["trace[1029726045] 'process raft request' (duration: 359.868958ms)","trace[1029726045] 'compare' (duration: 205.166441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:55.555336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.989499Z","time spent":"565.688776ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:26:55.601807Z","caller":"traceutil/trace.go:171","msg":"trace[1464197353] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1138; }","duration":"185.914677ms","start":"2026-07-01T07:26:55.415874Z","end":"2026-07-01T07:26:55.601789Z","steps":["trace[1464197353] 'read index received' (duration: 185.67357ms)","trace[1464197353] 'applied index is now lower than readState.Index' (duration: 239.957µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:55.60215Z","caller":"traceutil/trace.go:171","msg":"trace[1815655295] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"612.612876ms","start":"2026-07-01T07:26:54.989527Z","end":"2026-07-01T07:26:55.60214Z","steps":["trace[1815655295] 'process raft request' (duration: 612.086481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.602283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.989498Z","time spent":"612.705487ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:26:55.602349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.458512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:26:55.602431Z","caller":"traceutil/trace.go:171","msg":"trace[241997130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"186.599575ms","start":"2026-07-01T07:26:55.415813Z","end":"2026-07-01T07:26:55.602413Z","steps":["trace[241997130] 'agreement among raft nodes before linearized reading' (duration: 186.344258ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.602677Z","caller":"traceutil/trace.go:171","msg":"trace[209129484] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"612.990626ms","start":"2026-07-01T07:26:54.989674Z","end":"2026-07-01T07:26:55.602665Z","steps":["trace[209129484] 'process raft request' (duration: 612.03726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.602799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.989654Z","time spent":"613.056367ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:26:55.602931Z","caller":"traceutil/trace.go:171","msg":"trace[202084241] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"612.570594ms","start":"2026-07-01T07:26:54.990345Z","end":"2026-07-01T07:26:55.602916Z","steps":["trace[202084241] 'process raft request' (duration: 611.391812ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.603107Z","caller":"traceutil/trace.go:171","msg":"trace[1829839479] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"266.123513ms","start":"2026-07-01T07:26:55.336959Z","end":"2026-07-01T07:26:55.603083Z","steps":["trace[1829839479] 'process raft request' (duration: 264.802417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.603138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:54.990336Z","time spent":"612.637016ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:11.332576Z","caller":"traceutil/trace.go:171","msg":"trace[1392584153] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"112.373643ms","start":"2026-07-01T07:27:11.220184Z","end":"2026-07-01T07:27:11.332557Z","steps":["trace[1392584153] 'process raft request' (duration: 112.244129ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:13.156273Z","caller":"traceutil/trace.go:171","msg":"trace[1754824791] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"111.434126ms","start":"2026-07-01T07:27:13.044821Z","end":"2026-07-01T07:27:13.156255Z","steps":["trace[1754824791] 'process raft request' (duration: 111.296943ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.97434Z","caller":"traceutil/trace.go:171","msg":"trace[1203928439] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"177.446446ms","start":"2026-07-01T07:27:29.79687Z","end":"2026-07-01T07:27:29.974316Z","steps":["trace[1203928439] 'read index received' (duration: 177.24381ms)","trace[1203928439] 'applied index is now lower than readState.Index' (duration: 201.896µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:29.974423Z","caller":"traceutil/trace.go:171","msg":"trace[659503231] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"206.164929ms","start":"2026-07-01T07:27:29.768216Z","end":"2026-07-01T07:27:29.974381Z","steps":["trace[659503231] 'process raft request' (duration: 205.952773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:29.974568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.984023ms","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-07-01T07:27:29.974571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.697802ms","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-07-01T07:27:29.974595Z","caller":"traceutil/trace.go:171","msg":"trace[1439213364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"155.068056ms","start":"2026-07-01T07:27:29.81952Z","end":"2026-07-01T07:27:29.974588Z","steps":["trace[1439213364] 'agreement among raft nodes before linearized reading' (duration: 154.960613ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.974627Z","caller":"traceutil/trace.go:171","msg":"trace[1075770387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"177.779805ms","start":"2026-07-01T07:27:29.796832Z","end":"2026-07-01T07:27:29.974612Z","steps":["trace[1075770387] 'agreement among raft nodes before linearized reading' (duration: 177.58202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:29.974632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.572314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T07:27:29.974671Z","caller":"traceutil/trace.go:171","msg":"trace[231422873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"157.672256ms","start":"2026-07-01T07:27:29.816988Z","end":"2026-07-01T07:27:29.974661Z","steps":["trace[231422873] 'agreement among raft nodes before linearized reading' (duration: 157.459791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:29.974776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.119607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:27:29.975006Z","caller":"traceutil/trace.go:171","msg":"trace[2130935448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"155.260581ms","start":"2026-07-01T07:27:29.819576Z","end":"2026-07-01T07:27:29.974837Z","steps":["trace[2130935448] 'agreement among raft nodes before linearized reading' (duration: 154.890491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.171901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.39038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:30.171999Z","caller":"traceutil/trace.go:171","msg":"trace[1712729624] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"193.39077ms","start":"2026-07-01T07:27:29.97859Z","end":"2026-07-01T07:27:30.171981Z","steps":["trace[1712729624] 'process raft request' (duration: 90.795084ms)","trace[1712729624] 'compare' (duration: 102.276427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:31.083018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.762133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:31.084017Z","caller":"traceutil/trace.go:171","msg":"trace[198232592] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.105117527s","start":"2026-07-01T07:27:29.978876Z","end":"2026-07-01T07:27:31.083993Z","steps":["trace[198232592] 'process raft request' (duration: 729.285425ms)","trace[198232592] 'compare' (duration: 374.340751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:31.084135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:29.978861Z","time spent":"1.105204909s","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:31.084245Z","caller":"traceutil/trace.go:171","msg":"trace[1240417037] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1331; }","duration":"760.134415ms","start":"2026-07-01T07:27:30.32409Z","end":"2026-07-01T07:27:31.084224Z","steps":["trace[1240417037] 'read index received' (duration: 384.137579ms)","trace[1240417037] 'applied index is now lower than readState.Index' (duration: 375.995156ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:31.084514Z","caller":"traceutil/trace.go:171","msg":"trace[547363511] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.105001493s","start":"2026-07-01T07:27:29.979217Z","end":"2026-07-01T07:27:31.084219Z","steps":["trace[547363511] 'process raft request' (duration: 1.104722886s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.08454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.456184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T07:27:31.084567Z","caller":"traceutil/trace.go:171","msg":"trace[1944815765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1286; }","duration":"760.485505ms","start":"2026-07-01T07:27:30.324076Z","end":"2026-07-01T07:27:31.084561Z","steps":["trace[1944815765] 'agreement among raft nodes before linearized reading' (duration: 760.435503ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:31.084479Z","caller":"traceutil/trace.go:171","msg":"trace[1175702567] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.104824099s","start":"2026-07-01T07:27:29.979649Z","end":"2026-07-01T07:27:31.084473Z","steps":["trace[1175702567] 'process raft request' (duration: 1.104381287s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.084616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.362291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:31.084628Z","caller":"traceutil/trace.go:171","msg":"trace[450952660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1286; }","duration":"702.405592ms","start":"2026-07-01T07:27:30.38222Z","end":"2026-07-01T07:27:31.084625Z","steps":["trace[450952660] 'agreement among raft nodes before linearized reading' (duration: 702.385702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.084646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:30.382208Z","time spent":"702.434513ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:31.084673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:29.979206Z","time spent":"1.105362494s","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:31.084782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.552348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:31.084859Z","caller":"traceutil/trace.go:171","msg":"trace[290696884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"512.668161ms","start":"2026-07-01T07:27:30.572177Z","end":"2026-07-01T07:27:31.084845Z","steps":["trace[290696884] 'agreement among raft nodes before linearized reading' (duration: 512.525837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.084754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:29.979641Z","time spent":"1.104997174s","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:31.084583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:30.324059Z","time spent":"760.520806ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:31.085344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:30.572155Z","time spent":"512.772134ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:27:31.585064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.458503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:27:31.585162Z","caller":"traceutil/trace.go:171","msg":"trace[81802569] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1286; }","duration":"382.615628ms","start":"2026-07-01T07:27:31.202524Z","end":"2026-07-01T07:27:31.58514Z","steps":["trace[81802569] 'range keys from in-memory index tree' (duration: 382.242777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.585212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:31.202504Z","time spent":"382.689659ms","remote":"127.0.0.1:37466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-07-01T07:27:31.585397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.788977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:31.585504Z","caller":"traceutil/trace.go:171","msg":"trace[1696141020] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"371.395442ms","start":"2026-07-01T07:27:31.214088Z","end":"2026-07-01T07:27:31.585484Z","steps":["trace[1696141020] 'process raft request' (duration: 70.43103ms)","trace[1696141020] 'compare' (duration: 300.653193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:31.585579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:31.214062Z","time spent":"371.488654ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:32.217667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.887534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:32.217762Z","caller":"traceutil/trace.go:171","msg":"trace[1892979716] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1291; }","duration":"154.047668ms","start":"2026-07-01T07:27:32.063695Z","end":"2026-07-01T07:27:32.217742Z","steps":["trace[1892979716] 'range keys from in-memory index tree' (duration: 153.801571ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:32.399125Z","caller":"traceutil/trace.go:171","msg":"trace[252196449] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"177.148237ms","start":"2026-07-01T07:27:32.22195Z","end":"2026-07-01T07:27:32.399098Z","steps":["trace[252196449] 'process raft request' (duration: 177.004494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:32.721204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.389566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:32.721405Z","caller":"traceutil/trace.go:171","msg":"trace[909633234] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"309.235938ms","start":"2026-07-01T07:27:32.412153Z","end":"2026-07-01T07:27:32.721389Z","steps":["trace[909633234] 'process raft request' (duration: 309.203097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:32.721469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.412138Z","time spent":"309.305119ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:27:32.721773Z","caller":"traceutil/trace.go:171","msg":"trace[1894260503] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"357.890423ms","start":"2026-07-01T07:27:32.363869Z","end":"2026-07-01T07:27:32.72176Z","steps":["trace[1894260503] 'read index received' (duration: 35.528658ms)","trace[1894260503] 'applied index is now lower than readState.Index' (duration: 322.360245ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:32.72186Z","caller":"traceutil/trace.go:171","msg":"trace[1139461492] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"499.637347ms","start":"2026-07-01T07:27:32.22219Z","end":"2026-07-01T07:27:32.721828Z","steps":["trace[1139461492] 'process raft request' (duration: 296.371197ms)","trace[1139461492] 'compare' (duration: 202.124749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:32.721911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.039628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:32.721892Z","caller":"traceutil/trace.go:171","msg":"trace[2049540060] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"316.859865ms","start":"2026-07-01T07:27:32.404952Z","end":"2026-07-01T07:27:32.721812Z","steps":["trace[2049540060] 'process raft request' (duration: 316.350631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:32.722049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.222171Z","time spent":"499.759129ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:32.722041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.738121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-01T07:27:32.721954Z","caller":"traceutil/trace.go:171","msg":"trace[912650272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1295; }","duration":"358.11487ms","start":"2026-07-01T07:27:32.363819Z","end":"2026-07-01T07:27:32.721933Z","steps":["trace[912650272] 'agreement among raft nodes before linearized reading' (duration: 358.030557ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:32.722122Z","caller":"traceutil/trace.go:171","msg":"trace[2079009862] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1295; }","duration":"309.864314ms","start":"2026-07-01T07:27:32.41224Z","end":"2026-07-01T07:27:32.722105Z","steps":["trace[2079009862] 'agreement among raft nodes before linearized reading' (duration: 309.641008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:32.7221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.404928Z","time spent":"317.136513ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:27:32.722171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.412224Z","time spent":"309.937997ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T07:27:32.722172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.363798Z","time spent":"358.358626ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:32.722881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.978545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:32.722994Z","caller":"traceutil/trace.go:171","msg":"trace[257258703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"151.210031ms","start":"2026-07-01T07:27:32.571769Z","end":"2026-07-01T07:27:32.722979Z","steps":["trace[257258703] 'agreement among raft nodes before linearized reading' (duration: 150.200394ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:33.455661Z","caller":"traceutil/trace.go:171","msg":"trace[1025854389] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"727.177527ms","start":"2026-07-01T07:27:32.728458Z","end":"2026-07-01T07:27:33.455636Z","steps":["trace[1025854389] 'process raft request' (duration: 727.030603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:33.455817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.728444Z","time spent":"727.28769ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:33.625037Z","caller":"traceutil/trace.go:171","msg":"trace[307524297] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"537.212809ms","start":"2026-07-01T07:27:33.087805Z","end":"2026-07-01T07:27:33.625018Z","steps":["trace[307524297] 'read index received' (duration: 367.755331ms)","trace[307524297] 'applied index is now lower than readState.Index' (duration: 169.456348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:33.62521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.090169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:27:33.625235Z","caller":"traceutil/trace.go:171","msg":"trace[6483164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"536.157711ms","start":"2026-07-01T07:27:33.089071Z","end":"2026-07-01T07:27:33.625229Z","steps":["trace[6483164] 'agreement among raft nodes before linearized reading' (duration: 536.044898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:33.625255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:33.089059Z","time spent":"536.191172ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T07:27:33.625217Z","caller":"traceutil/trace.go:171","msg":"trace[1237574464] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"896.274176ms","start":"2026-07-01T07:27:32.728748Z","end":"2026-07-01T07:27:33.625022Z","steps":["trace[1237574464] 'process raft request' (duration: 879.808377ms)","trace[1237574464] 'compare' (duration: 15.838422ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:33.625266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.994637ms","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-07-01T07:27:33.625283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.451477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T07:27:33.625316Z","caller":"traceutil/trace.go:171","msg":"trace[989672210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"536.069119ms","start":"2026-07-01T07:27:33.089234Z","end":"2026-07-01T07:27:33.625303Z","steps":["trace[989672210] 'agreement among raft nodes before linearized reading' (duration: 535.944746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:33.625354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:33.089226Z","time spent":"536.12141ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T07:27:33.625359Z","caller":"traceutil/trace.go:171","msg":"trace[1172152101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"537.58668ms","start":"2026-07-01T07:27:33.087753Z","end":"2026-07-01T07:27:33.62534Z","steps":["trace[1172152101] 'agreement among raft nodes before linearized reading' (duration: 537.327663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:33.62536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:32.728729Z","time spent":"896.573953ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:27:33.625405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:33.08773Z","time spent":"537.666543ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T07:27:33.998978Z","caller":"traceutil/trace.go:171","msg":"trace[889453336] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"369.480659ms","start":"2026-07-01T07:27:33.629468Z","end":"2026-07-01T07:27:33.998948Z","steps":["trace[889453336] 'process raft request' (duration: 369.320924ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:33.999157Z","caller":"traceutil/trace.go:171","msg":"trace[1522633318] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"369.141299ms","start":"2026-07-01T07:27:33.629983Z","end":"2026-07-01T07:27:33.999125Z","steps":["trace[1522633318] 'process raft request' (duration: 368.924044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:33.999387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:33.629963Z","time spent":"369.280774ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:33.99941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:33.629429Z","time spent":"369.679214ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:34.062457Z","caller":"traceutil/trace.go:171","msg":"trace[1108381845] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1348; }","duration":"117.243985ms","start":"2026-07-01T07:27:33.945182Z","end":"2026-07-01T07:27:34.062426Z","steps":["trace[1108381845] 'read index received' (duration: 53.680772ms)","trace[1108381845] 'applied index is now lower than readState.Index' (duration: 63.562003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:34.062606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.4027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:34.062686Z","caller":"traceutil/trace.go:171","msg":"trace[1159645814] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1302; }","duration":"117.535903ms","start":"2026-07-01T07:27:33.945137Z","end":"2026-07-01T07:27:34.062673Z","steps":["trace[1159645814] 'agreement among raft nodes before linearized reading' (duration: 117.3955ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:34.063119Z","caller":"traceutil/trace.go:171","msg":"trace[757714442] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"240.521445ms","start":"2026-07-01T07:27:33.821959Z","end":"2026-07-01T07:27:34.06248Z","steps":["trace[757714442] 'process raft request' (duration: 199.558799ms)","trace[757714442] 'compare' (duration: 40.814342ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:41.410428Z","caller":"traceutil/trace.go:171","msg":"trace[1124902484] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"183.682996ms","start":"2026-07-01T07:27:41.226719Z","end":"2026-07-01T07:27:41.410402Z","steps":["trace[1124902484] 'read index received' (duration: 183.632614ms)","trace[1124902484] 'applied index is now lower than readState.Index' (duration: 48.952µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:41.410655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.927292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-01T07:27:41.410741Z","caller":"traceutil/trace.go:171","msg":"trace[527278518] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1359; }","duration":"184.053996ms","start":"2026-07-01T07:27:41.226668Z","end":"2026-07-01T07:27:41.410722Z","steps":["trace[527278518] 'agreement among raft nodes before linearized reading' (duration: 183.833239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:41.859559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.773297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-01T07:27:41.859683Z","caller":"traceutil/trace.go:171","msg":"trace[957298447] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1360; }","duration":"442.935001ms","start":"2026-07-01T07:27:41.416726Z","end":"2026-07-01T07:27:41.859661Z","steps":["trace[957298447] 'range keys from in-memory index tree' (duration: 442.569821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:41.860156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:41.416713Z","time spent":"443.008983ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-01T07:27:41.860663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.780177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:41.860782Z","caller":"traceutil/trace.go:171","msg":"trace[368960769] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"290.016764ms","start":"2026-07-01T07:27:41.570744Z","end":"2026-07-01T07:27:41.86076Z","steps":["trace[368960769] 'read index received' (duration: 39.842µs)","trace[368960769] 'applied index is now lower than readState.Index' (duration: 289.974672ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:41.860842Z","caller":"traceutil/trace.go:171","msg":"trace[1642542442] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"443.814205ms","start":"2026-07-01T07:27:41.417001Z","end":"2026-07-01T07:27:41.860815Z","steps":["trace[1642542442] 'process raft request' (duration: 40.691709ms)","trace[1642542442] 'compare' (duration: 402.242472ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:41.86093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.172018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:27:41.86099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:41.41699Z","time spent":"443.942349ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:41.861017Z","caller":"traceutil/trace.go:171","msg":"trace[602693559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"290.311212ms","start":"2026-07-01T07:27:41.570687Z","end":"2026-07-01T07:27:41.860998Z","steps":["trace[602693559] 'agreement among raft nodes before linearized reading' (duration: 290.158318ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:41.912254Z","caller":"traceutil/trace.go:171","msg":"trace[1054959961] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"264.022025ms","start":"2026-07-01T07:27:41.647696Z","end":"2026-07-01T07:27:41.911718Z","steps":["trace[1054959961] 'process raft request' (duration: 263.811949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:41.912566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.053398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:27:41.912636Z","caller":"traceutil/trace.go:171","msg":"trace[1323961724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1362; }","duration":"238.14486ms","start":"2026-07-01T07:27:41.674459Z","end":"2026-07-01T07:27:41.912604Z","steps":["trace[1323961724] 'agreement among raft nodes before linearized reading' (duration: 237.229175ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:42.072672Z","caller":"traceutil/trace.go:171","msg":"trace[141648186] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"153.124413ms","start":"2026-07-01T07:27:41.919519Z","end":"2026-07-01T07:27:42.072644Z","steps":["trace[141648186] 'process raft request' (duration: 150.991905ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:42.072843Z","caller":"traceutil/trace.go:171","msg":"trace[516741521] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"151.731005ms","start":"2026-07-01T07:27:41.921086Z","end":"2026-07-01T07:27:42.072817Z","steps":["trace[516741521] 'process raft request' (duration: 151.493609ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:29.475811Z","caller":"traceutil/trace.go:171","msg":"trace[309288365] linearizableReadLoop","detail":"{readStateIndex:1642; appliedIndex:1641; }","duration":"133.788095ms","start":"2026-07-01T07:28:29.341985Z","end":"2026-07-01T07:28:29.475773Z","steps":["trace[309288365] 'read index received' (duration: 133.494077ms)","trace[309288365] 'applied index is now lower than readState.Index' (duration: 292.308µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:29.476008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.98406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:29.47606Z","caller":"traceutil/trace.go:171","msg":"trace[895096003] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1582; }","duration":"134.080773ms","start":"2026-07-01T07:28:29.341963Z","end":"2026-07-01T07:28:29.476044Z","steps":["trace[895096003] 'agreement among raft nodes before linearized reading' (duration: 133.948899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:29.476111Z","caller":"traceutil/trace.go:171","msg":"trace[1828884380] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"184.652777ms","start":"2026-07-01T07:28:29.29142Z","end":"2026-07-01T07:28:29.476073Z","steps":["trace[1828884380] 'process raft request' (duration: 184.04857ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:23.35366Z","caller":"traceutil/trace.go:171","msg":"trace[1091040479] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"118.737188ms","start":"2026-07-01T07:31:23.2349Z","end":"2026-07-01T07:31:23.353637Z","steps":["trace[1091040479] 'process raft request' (duration: 118.591904ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:23.354758Z","caller":"traceutil/trace.go:171","msg":"trace[990312942] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"119.686914ms","start":"2026-07-01T07:31:23.235051Z","end":"2026-07-01T07:31:23.354738Z","steps":["trace[990312942] 'process raft request' (duration: 119.444068ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:29.3841Z","caller":"traceutil/trace.go:171","msg":"trace[2144323426] linearizableReadLoop","detail":"{readStateIndex:2530; appliedIndex:2529; }","duration":"105.356382ms","start":"2026-07-01T07:31:29.278719Z","end":"2026-07-01T07:31:29.384075Z","steps":["trace[2144323426] 'read index received' (duration: 105.002423ms)","trace[2144323426] 'applied index is now lower than readState.Index' (duration: 352.959µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:31:29.384146Z","caller":"traceutil/trace.go:171","msg":"trace[317479351] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"119.287503ms","start":"2026-07-01T07:31:29.264829Z","end":"2026-07-01T07:31:29.384117Z","steps":["trace[317479351] 'process raft request' (duration: 119.108368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:29.384243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.518427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:31:29.384278Z","caller":"traceutil/trace.go:171","msg":"trace[2104227236] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2433; }","duration":"105.626199ms","start":"2026-07-01T07:31:29.27864Z","end":"2026-07-01T07:31:29.384266Z","steps":["trace[2104227236] 'agreement among raft nodes before linearized reading' (duration: 105.555707ms)"],"step_count":1}