{"level":"warn","ts":"2026-03-13T23:01:19.190603Z","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-13T23:01:19.190694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-13T23:01:19.190762Z","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-13T23:01:19.190773Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-13T23:01:19.1908Z","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-13T23:01:19.191414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-03-13T23:01:19.191485Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-13T23:01:19.200644Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.953886ms"} {"level":"info","ts":"2026-03-13T23:01:19.216208Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-03-13T23:01:19.216301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:01:19.216414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-03-13T23:01:19.216427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:01:19.216436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-03-13T23:01:19.2165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-03-13T23:01:19.225707Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:01:19.227269Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:01:19.228971Z","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-13T23:01:19.231258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:01:19.231327Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:01:19.231441Z","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-13T23:01:19.231479Z","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-13T23:01:19.231492Z","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-13T23:01:19.232228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-03-13T23:01:19.232316Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-13T23:01:19.235685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-13T23:01:19.23571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-13T23:01:19.235653Z","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-13T23:01:19.23612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:01:19.236261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:01:19.716741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:01:19.716876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:01:19.71707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-03-13T23:01:19.717099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:01:19.717153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-13T23:01:19.717227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-03-13T23:01:19.717248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-13T23:01:19.736546Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:01:19.736826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:01:19.736885Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:01:19.737322Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:19.737416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:01:19.737437Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:01:19.739206Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:19.739275Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:19.739297Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:19.740094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-03-13T23:01:19.740102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T23:01:23.470923Z","caller":"traceutil/trace.go:171","msg":"trace[690377935] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"622.997473ms","start":"2026-03-13T23:01:22.847902Z","end":"2026-03-13T23:01:23.470899Z","steps":["trace[690377935] 'process raft request' (duration: 622.845898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:23.471403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:22.847876Z","time spent":"623.131105ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:01:24.624742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153924639s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:01:24.706691Z","caller":"traceutil/trace.go:171","msg":"trace[442330938] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:202; }","duration":"1.461409819s","start":"2026-03-13T23:01:23.24525Z","end":"2026-03-13T23:01:24.70666Z","steps":["trace[442330938] 'read index received' (duration: 225.55332ms)","trace[442330938] 'applied index is now lower than readState.Index' (duration: 1.235855129s)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:01:24.706812Z","caller":"traceutil/trace.go:171","msg":"trace[1221638822] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"1.653020787s","start":"2026-03-13T23:01:23.053741Z","end":"2026-03-13T23:01:24.706762Z","steps":["trace[1221638822] 'process raft request' (duration: 1.571354084s)","trace[1221638822] 'compare' (duration: 81.425347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:24.706894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461629653s","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-13T23:01:24.706971Z","caller":"traceutil/trace.go:171","msg":"trace[807982525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:199; }","duration":"1.461804828s","start":"2026-03-13T23:01:23.245155Z","end":"2026-03-13T23:01:24.706959Z","steps":["trace[807982525] 'agreement among raft nodes before linearized reading' (duration: 1.461622313s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:24.706987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:23.053723Z","time spent":"1.653175961s","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:01:24.707011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:23.245116Z","time spent":"1.46188465s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:01:24.713819Z","caller":"traceutil/trace.go:171","msg":"trace[1989286409] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"1.23840561s","start":"2026-03-13T23:01:23.475389Z","end":"2026-03-13T23:01:24.713795Z","steps":["trace[1989286409] 'process raft request' (duration: 1.238297047s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:24.713951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:23.475367Z","time spent":"1.238525513s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:01:24.714129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248450432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:24.714189Z","caller":"traceutil/trace.go:171","msg":"trace[1722525361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:200; }","duration":"1.248555964s","start":"2026-03-13T23:01:23.465613Z","end":"2026-03-13T23:01:24.714169Z","steps":["trace[1722525361] 'agreement among raft nodes before linearized reading' (duration: 1.248425801s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:24.714227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:23.465565Z","time spent":"1.248653797s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:01:24.714215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038730955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:24.714347Z","caller":"traceutil/trace.go:171","msg":"trace[663618025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:200; }","duration":"1.038893089s","start":"2026-03-13T23:01:23.675413Z","end":"2026-03-13T23:01:24.714306Z","steps":["trace[663618025] 'agreement among raft nodes before linearized reading' (duration: 1.038670513s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:24.714395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:23.675398Z","time spent":"1.038988542s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:01:25.742244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.160958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:01:25.742818Z","caller":"traceutil/trace.go:171","msg":"trace[964603650] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"1.031829149s","start":"2026-03-13T23:01:24.710946Z","end":"2026-03-13T23:01:25.742775Z","steps":["trace[964603650] 'process raft request' (duration: 755.912423ms)","trace[964603650] 'compare' (duration: 274.655305ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:25.742938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:24.710915Z","time spent":"1.031962523s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:01:25.742987Z","caller":"traceutil/trace.go:171","msg":"trace[1588870895] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:205; }","duration":"570.581786ms","start":"2026-03-13T23:01:25.172388Z","end":"2026-03-13T23:01:25.74297Z","steps":["trace[1588870895] 'read index received' (duration: 294.493465ms)","trace[1588870895] 'applied index is now lower than readState.Index' (duration: 276.087311ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:25.743071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.681548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:25.743108Z","caller":"traceutil/trace.go:171","msg":"trace[2034733687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:204; }","duration":"570.76021ms","start":"2026-03-13T23:01:25.172332Z","end":"2026-03-13T23:01:25.743092Z","steps":["trace[2034733687] 'agreement among raft nodes before linearized reading' (duration: 570.704659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:25.743143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:25.172308Z","time spent":"570.824952ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T23:01:25.743172Z","caller":"traceutil/trace.go:171","msg":"trace[525134959] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"1.02606338s","start":"2026-03-13T23:01:24.717098Z","end":"2026-03-13T23:01:25.743161Z","steps":["trace[525134959] 'process raft request' (duration: 1.0252484s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:25.74322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:24.717073Z","time spent":"1.026125542s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:01:25.743165Z","caller":"traceutil/trace.go:171","msg":"trace[1199389291] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"1.024976544s","start":"2026-03-13T23:01:24.718147Z","end":"2026-03-13T23:01:25.743124Z","steps":["trace[1199389291] 'process raft request' (duration: 1.024734398s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:25.743248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:25.043331Z","time spent":"699.9157ms","remote":"127.0.0.1:43186","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T23:01:25.74331Z","caller":"traceutil/trace.go:171","msg":"trace[760152880] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"1.025760253s","start":"2026-03-13T23:01:24.717536Z","end":"2026-03-13T23:01:25.743297Z","steps":["trace[760152880] 'process raft request' (duration: 1.025308802s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:25.743349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:24.717526Z","time spent":"1.025804113s","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:01:25.743393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:24.718139Z","time spent":"1.025133507s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:01:51.715539Z","caller":"traceutil/trace.go:171","msg":"trace[212417080] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"285.093131ms","start":"2026-03-13T23:01:51.430418Z","end":"2026-03-13T23:01:51.715511Z","steps":["trace[212417080] 'process raft request' (duration: 284.915396ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:53.98398Z","caller":"traceutil/trace.go:171","msg":"trace[342554068] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"100.668415ms","start":"2026-03-13T23:01:53.883278Z","end":"2026-03-13T23:01:53.983946Z","steps":["trace[342554068] 'process raft request' (duration: 100.494121ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:57.935732Z","caller":"traceutil/trace.go:171","msg":"trace[1282164411] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"141.377687ms","start":"2026-03-13T23:01:57.794331Z","end":"2026-03-13T23:01:57.935708Z","steps":["trace[1282164411] 'process raft request' (duration: 141.232283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.417526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.001336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T23:01:58.417614Z","caller":"traceutil/trace.go:171","msg":"trace[1591913230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"390.12266ms","start":"2026-03-13T23:01:58.027468Z","end":"2026-03-13T23:01:58.417591Z","steps":["trace[1591913230] 'range keys from in-memory index tree' (duration: 389.832832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.417661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:58.027457Z","time spent":"390.193881ms","remote":"127.0.0.1:43378","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-13T23:01:58.417673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.003193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:58.417766Z","caller":"traceutil/trace.go:171","msg":"trace[504183319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"245.139755ms","start":"2026-03-13T23:01:58.172602Z","end":"2026-03-13T23:01:58.417742Z","steps":["trace[504183319] 'range keys from in-memory index tree' (duration: 244.923031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.418154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.137402ms","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-03-13T23:01:58.418203Z","caller":"traceutil/trace.go:171","msg":"trace[1481505480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"421.185123ms","start":"2026-03-13T23:01:57.997006Z","end":"2026-03-13T23:01:58.418191Z","steps":["trace[1481505480] 'range keys from in-memory index tree' (duration: 420.993408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.418281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:57.99694Z","time spent":"421.330566ms","remote":"127.0.0.1:43378","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-03-13T23:01:58.418417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.252285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:01:58.418488Z","caller":"traceutil/trace.go:171","msg":"trace[980979835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"398.312406ms","start":"2026-03-13T23:01:58.020147Z","end":"2026-03-13T23:01:58.418459Z","steps":["trace[980979835] 'range keys from in-memory index tree' (duration: 398.09336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.418494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.047509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-13T23:01:58.418573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:58.020101Z","time spent":"398.46385ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:01:58.418608Z","caller":"traceutil/trace.go:171","msg":"trace[2088800071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"398.212443ms","start":"2026-03-13T23:01:58.02037Z","end":"2026-03-13T23:01:58.418583Z","steps":["trace[2088800071] 'range keys from in-memory index tree' (duration: 397.805583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:58.418723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:58.020349Z","time spent":"398.362296ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T23:02:00.690397Z","caller":"traceutil/trace.go:171","msg":"trace[1238872963] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"237.581116ms","start":"2026-03-13T23:02:00.452777Z","end":"2026-03-13T23:02:00.690358Z","steps":["trace[1238872963] 'read index received' (duration: 237.407611ms)","trace[1238872963] 'applied index is now lower than readState.Index' (duration: 172.324µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:00.690496Z","caller":"traceutil/trace.go:171","msg":"trace[2110460655] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"244.175563ms","start":"2026-03-13T23:02:00.446288Z","end":"2026-03-13T23:02:00.690463Z","steps":["trace[2110460655] 'process raft request' (duration: 243.913787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:00.690717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.75059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-13T23:02:00.690746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.952474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:02:00.690791Z","caller":"traceutil/trace.go:171","msg":"trace[1062799363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"237.861242ms","start":"2026-03-13T23:02:00.452915Z","end":"2026-03-13T23:02:00.690776Z","steps":["trace[1062799363] 'agreement among raft nodes before linearized reading' (duration: 237.685448ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:00.690795Z","caller":"traceutil/trace.go:171","msg":"trace[1904900419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"238.031986ms","start":"2026-03-13T23:02:00.452748Z","end":"2026-03-13T23:02:00.69078Z","steps":["trace[1904900419] 'agreement among raft nodes before linearized reading' (duration: 237.77702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:00.690807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.428822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-13T23:02:00.690884Z","caller":"traceutil/trace.go:171","msg":"trace[1854446373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"237.557115ms","start":"2026-03-13T23:02:00.453308Z","end":"2026-03-13T23:02:00.690865Z","steps":["trace[1854446373] 'agreement among raft nodes before linearized reading' (duration: 237.348811ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:00.840695Z","caller":"traceutil/trace.go:171","msg":"trace[1711324901] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"145.984458ms","start":"2026-03-13T23:02:00.694685Z","end":"2026-03-13T23:02:00.84067Z","steps":["trace[1711324901] 'process raft request' (duration: 145.841224ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:00.853357Z","caller":"traceutil/trace.go:171","msg":"trace[988928240] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"158.441926ms","start":"2026-03-13T23:02:00.694799Z","end":"2026-03-13T23:02:00.853241Z","steps":["trace[988928240] 'process raft request' (duration: 158.20679ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:00.856295Z","caller":"traceutil/trace.go:171","msg":"trace[1631324729] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"159.525761ms","start":"2026-03-13T23:02:00.6948Z","end":"2026-03-13T23:02:00.854326Z","steps":["trace[1631324729] 'process raft request' (duration: 158.390904ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:02.843562Z","caller":"traceutil/trace.go:171","msg":"trace[1643514434] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"149.285005ms","start":"2026-03-13T23:02:02.694251Z","end":"2026-03-13T23:02:02.843536Z","steps":["trace[1643514434] 'read index received' (duration: 145.346952ms)","trace[1643514434] 'applied index is now lower than readState.Index' (duration: 3.936983ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:02.843684Z","caller":"traceutil/trace.go:171","msg":"trace[434973961] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"156.468798ms","start":"2026-03-13T23:02:02.687177Z","end":"2026-03-13T23:02:02.843646Z","steps":["trace[434973961] 'process raft request' (duration: 156.203122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:02.843973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.710566ms","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-13T23:02:02.844054Z","caller":"traceutil/trace.go:171","msg":"trace[435614854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"149.838299ms","start":"2026-03-13T23:02:02.6942Z","end":"2026-03-13T23:02:02.844038Z","steps":["trace[435614854] 'agreement among raft nodes before linearized reading' (duration: 149.497281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:19.453362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724900460983795,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:02:19.564153Z","caller":"traceutil/trace.go:171","msg":"trace[927207527] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"614.588702ms","start":"2026-03-13T23:02:18.949532Z","end":"2026-03-13T23:02:19.56412Z","steps":["trace[927207527] 'process raft request' (duration: 614.415838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:19.564572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:18.949514Z","time spent":"614.728656ms","remote":"127.0.0.1:43378","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-03-13T23:02:20.35973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.60516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:02:20.359828Z","caller":"traceutil/trace.go:171","msg":"trace[551945485] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:745; }","duration":"1.407487524s","start":"2026-03-13T23:02:18.952324Z","end":"2026-03-13T23:02:20.359812Z","steps":["trace[551945485] 'read index received' (duration: 611.646572ms)","trace[551945485] 'applied index is now lower than readState.Index' (duration: 795.839942ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:20.359909Z","caller":"traceutil/trace.go:171","msg":"trace[494550768] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.40811448s","start":"2026-03-13T23:02:18.951784Z","end":"2026-03-13T23:02:20.359899Z","steps":["trace[494550768] 'process raft request' (duration: 930.225913ms)","trace[494550768] 'compare' (duration: 477.306733ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:20.35996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:18.951771Z","time spent":"1.408161081s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:20.360777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407205298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:02:20.360794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.835137ms","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":"info","ts":"2026-03-13T23:02:20.360944Z","caller":"traceutil/trace.go:171","msg":"trace[548410109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:720; }","duration":"372.98324ms","start":"2026-03-13T23:02:19.987901Z","end":"2026-03-13T23:02:20.360884Z","steps":["trace[548410109] 'agreement among raft nodes before linearized reading' (duration: 372.798715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.360942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188876001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:20.361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:19.987883Z","time spent":"373.104083ms","remote":"127.0.0.1:43170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:02:20.361043Z","caller":"traceutil/trace.go:171","msg":"trace[1655965215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"1.189020315s","start":"2026-03-13T23:02:19.171998Z","end":"2026-03-13T23:02:20.361018Z","steps":["trace[1655965215] 'agreement among raft nodes before linearized reading' (duration: 1.188832711s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.361122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:19.171976Z","time spent":"1.189130457s","remote":"127.0.0.1:43110","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-03-13T23:02:20.361165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408808776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:02:20.361418Z","caller":"traceutil/trace.go:171","msg":"trace[479365404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.40894236s","start":"2026-03-13T23:02:18.95228Z","end":"2026-03-13T23:02:20.361223Z","steps":["trace[479365404] 'agreement among raft nodes before linearized reading' (duration: 1.407990046s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.361452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:18.952261Z","time spent":"1.409183435s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T23:02:20.361458Z","caller":"traceutil/trace.go:171","msg":"trace[1656881936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.40731514s","start":"2026-03-13T23:02:18.953535Z","end":"2026-03-13T23:02:20.36085Z","steps":["trace[1656881936] 'agreement among raft nodes before linearized reading' (duration: 1.407128806s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.361505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:18.953515Z","time spent":"1.407979206s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:02:20.72495Z","caller":"traceutil/trace.go:171","msg":"trace[2018182842] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"359.877167ms","start":"2026-03-13T23:02:20.365043Z","end":"2026-03-13T23:02:20.72492Z","steps":["trace[2018182842] 'process raft request' (duration: 359.15243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.725112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.365021Z","time spent":"360.00819ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:20.985482Z","caller":"traceutil/trace.go:171","msg":"trace[1032533411] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"616.349705ms","start":"2026-03-13T23:02:20.369108Z","end":"2026-03-13T23:02:20.985457Z","steps":["trace[1032533411] 'read index received' (duration: 355.098194ms)","trace[1032533411] 'applied index is now lower than readState.Index' (duration: 261.250651ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:20.985506Z","caller":"traceutil/trace.go:171","msg":"trace[1605420810] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"618.618478ms","start":"2026-03-13T23:02:20.366857Z","end":"2026-03-13T23:02:20.985475Z","steps":["trace[1605420810] 'process raft request' (duration: 618.395463ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:20.985608Z","caller":"traceutil/trace.go:171","msg":"trace[1352404100] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"618.71036ms","start":"2026-03-13T23:02:20.366888Z","end":"2026-03-13T23:02:20.985598Z","steps":["trace[1352404100] 'process raft request' (duration: 618.521865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.985635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.36684Z","time spent":"618.73152ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:20.985715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.366871Z","time spent":"618.766991ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:20.985746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.640421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:20.985773Z","caller":"traceutil/trace.go:171","msg":"trace[155602471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"616.670092ms","start":"2026-03-13T23:02:20.369091Z","end":"2026-03-13T23:02:20.985761Z","steps":["trace[155602471] 'agreement among raft nodes before linearized reading' (duration: 616.633001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.985792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.369085Z","time spent":"616.702362ms","remote":"127.0.0.1:43110","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-03-13T23:02:20.985803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.232282ms","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-13T23:02:20.985883Z","caller":"traceutil/trace.go:171","msg":"trace[249467925] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:723; }","duration":"611.351776ms","start":"2026-03-13T23:02:20.374512Z","end":"2026-03-13T23:02:20.985864Z","steps":["trace[249467925] 'agreement among raft nodes before linearized reading' (duration: 611.175121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.985934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.374504Z","time spent":"611.421268ms","remote":"127.0.0.1:43514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:02:20.985937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.219344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:02:20.985976Z","caller":"traceutil/trace.go:171","msg":"trace[632183824] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:723; }","duration":"542.298205ms","start":"2026-03-13T23:02:20.443666Z","end":"2026-03-13T23:02:20.985964Z","steps":["trace[632183824] 'agreement among raft nodes before linearized reading' (duration: 542.186953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:20.986001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:20.443645Z","time spent":"542.349967ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:02:22.088535Z","caller":"traceutil/trace.go:171","msg":"trace[1731868991] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"516.304424ms","start":"2026-03-13T23:02:21.572206Z","end":"2026-03-13T23:02:22.08851Z","steps":["trace[1731868991] 'process raft request' (duration: 516.164061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:22.088692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:21.57219Z","time spent":"516.417257ms","remote":"127.0.0.1:43378","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-03-13T23:02:22.339513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.43021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:22.339608Z","caller":"traceutil/trace.go:171","msg":"trace[587260352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"167.598324ms","start":"2026-03-13T23:02:22.17199Z","end":"2026-03-13T23:02:22.339588Z","steps":["trace[587260352] 'range keys from in-memory index tree' (duration: 167.295857ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:30.522355Z","caller":"traceutil/trace.go:171","msg":"trace[1772494080] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"119.910355ms","start":"2026-03-13T23:02:30.402409Z","end":"2026-03-13T23:02:30.522319Z","steps":["trace[1772494080] 'process raft request' (duration: 119.68762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:30.758712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.300819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:30.758807Z","caller":"traceutil/trace.go:171","msg":"trace[505315856] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:754; }","duration":"183.424971ms","start":"2026-03-13T23:02:30.57536Z","end":"2026-03-13T23:02:30.758785Z","steps":["trace[505315856] 'range keys from in-memory index tree' (duration: 183.241737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:30.758881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.233217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:30.758949Z","caller":"traceutil/trace.go:171","msg":"trace[163717103] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:754; }","duration":"183.321969ms","start":"2026-03-13T23:02:30.575609Z","end":"2026-03-13T23:02:30.758931Z","steps":["trace[163717103] 'range keys from in-memory index tree' (duration: 183.171976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:30.759053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.713378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:30.759136Z","caller":"traceutil/trace.go:171","msg":"trace[186589862] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:754; }","duration":"183.828951ms","start":"2026-03-13T23:02:30.575288Z","end":"2026-03-13T23:02:30.759117Z","steps":["trace[186589862] 'count revisions from in-memory index tree' (duration: 183.638697ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:31.211475Z","caller":"traceutil/trace.go:171","msg":"trace[464980706] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"180.868972ms","start":"2026-03-13T23:02:31.030566Z","end":"2026-03-13T23:02:31.211435Z","steps":["trace[464980706] 'read index received' (duration: 180.451322ms)","trace[464980706] 'applied index is now lower than readState.Index' (duration: 415.649µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:31.211558Z","caller":"traceutil/trace.go:171","msg":"trace[763527202] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"426.91841ms","start":"2026-03-13T23:02:30.784605Z","end":"2026-03-13T23:02:31.211523Z","steps":["trace[763527202] 'process raft request' (duration: 426.570901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:31.21172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:30.78458Z","time spent":"427.052403ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:31.211739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.153688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:02:31.211802Z","caller":"traceutil/trace.go:171","msg":"trace[1527607292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"181.278971ms","start":"2026-03-13T23:02:31.030508Z","end":"2026-03-13T23:02:31.211787Z","steps":["trace[1527607292] 'agreement among raft nodes before linearized reading' (duration: 181.072356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:31.2119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.64025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-13T23:02:31.211962Z","caller":"traceutil/trace.go:171","msg":"trace[1731362154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"176.795254ms","start":"2026-03-13T23:02:31.035152Z","end":"2026-03-13T23:02:31.211948Z","steps":["trace[1731362154] 'agreement among raft nodes before linearized reading' (duration: 176.513527ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:31.569239Z","caller":"traceutil/trace.go:171","msg":"trace[1692107499] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"352.596653ms","start":"2026-03-13T23:02:31.216607Z","end":"2026-03-13T23:02:31.569203Z","steps":["trace[1692107499] 'process raft request' (duration: 352.437949ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:31.569312Z","caller":"traceutil/trace.go:171","msg":"trace[846440815] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"352.986593ms","start":"2026-03-13T23:02:31.216261Z","end":"2026-03-13T23:02:31.569248Z","steps":["trace[846440815] 'process raft request' (duration: 291.089965ms)","trace[846440815] 'compare' (duration: 61.319095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:31.56948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:31.216586Z","time spent":"352.768717ms","remote":"127.0.0.1:43378","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-03-13T23:02:31.569522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:31.216249Z","time spent":"353.154957ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:45.668454Z","caller":"traceutil/trace.go:171","msg":"trace[1730820872] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"305.823718ms","start":"2026-03-13T23:02:45.362601Z","end":"2026-03-13T23:02:45.668424Z","steps":["trace[1730820872] 'process raft request' (duration: 305.657054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:45.669377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:45.362572Z","time spent":"305.97104ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:46.532252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.383646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:02:46.532944Z","caller":"traceutil/trace.go:171","msg":"trace[661598584] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"856.831455ms","start":"2026-03-13T23:02:45.676091Z","end":"2026-03-13T23:02:46.532923Z","steps":["trace[661598584] 'process raft request' (duration: 533.694071ms)","trace[661598584] 'compare' (duration: 322.272793ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:46.533211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:45.676078Z","time spent":"857.07678ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:46.534693Z","caller":"traceutil/trace.go:171","msg":"trace[1583211080] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"510.462272ms","start":"2026-03-13T23:02:46.024201Z","end":"2026-03-13T23:02:46.534664Z","steps":["trace[1583211080] 'read index received' (duration: 185.709269ms)","trace[1583211080] 'applied index is now lower than readState.Index' (duration: 324.741223ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:46.534819Z","caller":"traceutil/trace.go:171","msg":"trace[1374946547] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"857.387397ms","start":"2026-03-13T23:02:45.677421Z","end":"2026-03-13T23:02:46.534809Z","steps":["trace[1374946547] 'process raft request' (duration: 857.106421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:46.534934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:45.677404Z","time spent":"857.450519ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:46.53493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.445512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:46.534935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.714958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:46.535023Z","caller":"traceutil/trace.go:171","msg":"trace[569213190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"360.563385ms","start":"2026-03-13T23:02:46.174423Z","end":"2026-03-13T23:02:46.534986Z","steps":["trace[569213190] 'agreement among raft nodes before linearized reading' (duration: 360.436442ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:46.535028Z","caller":"traceutil/trace.go:171","msg":"trace[1525905550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"510.836701ms","start":"2026-03-13T23:02:46.024158Z","end":"2026-03-13T23:02:46.534995Z","steps":["trace[1525905550] 'agreement among raft nodes before linearized reading' (duration: 510.719848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:46.535068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:46.1744Z","time spent":"360.660547ms","remote":"127.0.0.1:43120","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-03-13T23:02:46.535078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:46.024138Z","time spent":"510.929322ms","remote":"127.0.0.1:43110","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-03-13T23:02:46.535284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.080606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T23:02:46.535624Z","caller":"traceutil/trace.go:171","msg":"trace[384418698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"240.450445ms","start":"2026-03-13T23:02:46.295157Z","end":"2026-03-13T23:02:46.535607Z","steps":["trace[384418698] 'agreement among raft nodes before linearized reading' (duration: 240.006045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:48.148503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.79099ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-13T23:02:48.786431Z","caller":"traceutil/trace.go:171","msg":"trace[939552553] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"207.00775ms","start":"2026-03-13T23:02:48.579388Z","end":"2026-03-13T23:02:48.786395Z","steps":["trace[939552553] 'process raft request' (duration: 206.875557ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:57.163915Z","caller":"traceutil/trace.go:171","msg":"trace[1443839676] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"321.31573ms","start":"2026-03-13T23:02:56.842561Z","end":"2026-03-13T23:02:57.163877Z","steps":["trace[1443839676] 'read index received' (duration: 320.967872ms)","trace[1443839676] 'applied index is now lower than readState.Index' (duration: 346.078µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:57.164108Z","caller":"traceutil/trace.go:171","msg":"trace[975883190] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"337.883069ms","start":"2026-03-13T23:02:56.82612Z","end":"2026-03-13T23:02:57.164003Z","steps":["trace[975883190] 'process raft request' (duration: 337.520241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:57.164313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.71971ms","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-03-13T23:02:57.164615Z","caller":"traceutil/trace.go:171","msg":"trace[1845085359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"322.066158ms","start":"2026-03-13T23:02:56.842519Z","end":"2026-03-13T23:02:57.164585Z","steps":["trace[1845085359] 'agreement among raft nodes before linearized reading' (duration: 321.494394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:57.164663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.54244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:57.164712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:56.8425Z","time spent":"322.166801ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:02:57.164732Z","caller":"traceutil/trace.go:171","msg":"trace[1560435461] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:826; }","duration":"290.661883ms","start":"2026-03-13T23:02:56.874048Z","end":"2026-03-13T23:02:57.16471Z","steps":["trace[1560435461] 'agreement among raft nodes before linearized reading' (duration: 290.357086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:57.164699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:56.826093Z","time spent":"338.33511ms","remote":"127.0.0.1:43378","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-03-13T23:02:57.788706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.70775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:02:57.78918Z","caller":"traceutil/trace.go:171","msg":"trace[919211793] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"252.988196ms","start":"2026-03-13T23:02:57.536141Z","end":"2026-03-13T23:02:57.789129Z","steps":["trace[919211793] 'process raft request' (duration: 252.723699ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:57.789223Z","caller":"traceutil/trace.go:171","msg":"trace[117526956] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"253.388355ms","start":"2026-03-13T23:02:57.535773Z","end":"2026-03-13T23:02:57.789161Z","steps":["trace[117526956] 'process raft request' (duration: 39.124312ms)","trace[117526956] 'compare' (duration: 213.396313ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:58.11346Z","caller":"traceutil/trace.go:171","msg":"trace[1586044352] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:866; }","duration":"253.701395ms","start":"2026-03-13T23:02:57.859721Z","end":"2026-03-13T23:02:58.113422Z","steps":["trace[1586044352] 'read index received' (duration: 16.853646ms)","trace[1586044352] 'applied index is now lower than readState.Index' (duration: 236.843148ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:58.113673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.915739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:58.113721Z","caller":"traceutil/trace.go:171","msg":"trace[1539680869] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:834; }","duration":"254.032512ms","start":"2026-03-13T23:02:57.85967Z","end":"2026-03-13T23:02:58.113702Z","steps":["trace[1539680869] 'agreement among raft nodes before linearized reading' (duration: 253.878328ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.113814Z","caller":"traceutil/trace.go:171","msg":"trace[1946476623] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"231.305205ms","start":"2026-03-13T23:02:57.882475Z","end":"2026-03-13T23:02:58.11378Z","steps":["trace[1946476623] 'process raft request' (duration: 230.856634ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.113818Z","caller":"traceutil/trace.go:171","msg":"trace[264944385] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"230.28609ms","start":"2026-03-13T23:02:57.883489Z","end":"2026-03-13T23:02:58.113775Z","steps":["trace[264944385] 'process raft request' (duration: 230.083445ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.114016Z","caller":"traceutil/trace.go:171","msg":"trace[1941408852] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"231.302525ms","start":"2026-03-13T23:02:57.882677Z","end":"2026-03-13T23:02:58.11398Z","steps":["trace[1941408852] 'process raft request' (duration: 230.843024ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.386286Z","caller":"traceutil/trace.go:171","msg":"trace[59297950] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"125.654513ms","start":"2026-03-13T23:02:58.260596Z","end":"2026-03-13T23:02:58.386251Z","steps":["trace[59297950] 'process raft request' (duration: 51.030098ms)","trace[59297950] 'compare' (duration: 74.462801ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:58.673743Z","caller":"traceutil/trace.go:171","msg":"trace[1453551480] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"184.724065ms","start":"2026-03-13T23:02:58.488986Z","end":"2026-03-13T23:02:58.67371Z","steps":["trace[1453551480] 'process raft request' (duration: 184.49758ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.695405Z","caller":"traceutil/trace.go:171","msg":"trace[225555789] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"205.194368ms","start":"2026-03-13T23:02:58.49017Z","end":"2026-03-13T23:02:58.695364Z","steps":["trace[225555789] 'process raft request' (duration: 204.974772ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:58.695418Z","caller":"traceutil/trace.go:171","msg":"trace[980413179] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"204.419469ms","start":"2026-03-13T23:02:58.49097Z","end":"2026-03-13T23:02:58.695389Z","steps":["trace[980413179] 'process raft request' (duration: 204.310036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:59.061014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.460822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:02:59.061317Z","caller":"traceutil/trace.go:171","msg":"trace[909957695] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"359.460907ms","start":"2026-03-13T23:02:58.701815Z","end":"2026-03-13T23:02:59.061275Z","steps":["trace[909957695] 'process raft request' (duration: 158.620405ms)","trace[909957695] 'compare' (duration: 200.140695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:59.061505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:58.701794Z","time spent":"359.609261ms","remote":"127.0.0.1:43272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:02:59.061523Z","caller":"traceutil/trace.go:171","msg":"trace[1402891311] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"359.221171ms","start":"2026-03-13T23:02:58.702228Z","end":"2026-03-13T23:02:59.061449Z","steps":["trace[1402891311] 'process raft request' (duration: 359.074847ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:59.061568Z","caller":"traceutil/trace.go:171","msg":"trace[1002657073] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"359.682692ms","start":"2026-03-13T23:02:58.701839Z","end":"2026-03-13T23:02:59.061522Z","steps":["trace[1002657073] 'process raft request' (duration: 359.367194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:59.061686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:58.702216Z","time spent":"359.432297ms","remote":"127.0.0.1:43380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1286,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:02:59.061786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:58.701812Z","time spent":"359.855746ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:59.062673Z","caller":"traceutil/trace.go:171","msg":"trace[1526868403] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"357.633323ms","start":"2026-03-13T23:02:58.70502Z","end":"2026-03-13T23:02:59.062653Z","steps":["trace[1526868403] 'process raft request' (duration: 357.49407ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:59.062698Z","caller":"traceutil/trace.go:171","msg":"trace[812703889] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"360.076523ms","start":"2026-03-13T23:02:58.702594Z","end":"2026-03-13T23:02:59.06267Z","steps":["trace[812703889] 'process raft request' (duration: 358.789201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:59.062784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:58.705007Z","time spent":"357.728175ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9123,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:02:59.064153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:58.702583Z","time spent":"360.168265ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:59.226689Z","caller":"traceutil/trace.go:171","msg":"trace[1551800502] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"155.835998ms","start":"2026-03-13T23:02:59.070816Z","end":"2026-03-13T23:02:59.226652Z","steps":["trace[1551800502] 'process raft request' (duration: 152.670023ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:59.233557Z","caller":"traceutil/trace.go:171","msg":"trace[1486741724] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"161.753102ms","start":"2026-03-13T23:02:59.071758Z","end":"2026-03-13T23:02:59.233511Z","steps":["trace[1486741724] 'process raft request' (duration: 161.152037ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:02.653875Z","caller":"traceutil/trace.go:171","msg":"trace[391458780] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"483.603355ms","start":"2026-03-13T23:03:02.170251Z","end":"2026-03-13T23:03:02.653854Z","steps":["trace[391458780] 'read index received' (duration: 483.479802ms)","trace[391458780] 'applied index is now lower than readState.Index' (duration: 122.693µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:02.654049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.793549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:02.654075Z","caller":"traceutil/trace.go:171","msg":"trace[754057151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"483.863881ms","start":"2026-03-13T23:03:02.170204Z","end":"2026-03-13T23:03:02.654068Z","steps":["trace[754057151] 'agreement among raft nodes before linearized reading' (duration: 483.770219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:02.654097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:02.170177Z","time spent":"483.914753ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:03:02.654054Z","caller":"traceutil/trace.go:171","msg":"trace[914878831] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"786.494362ms","start":"2026-03-13T23:03:01.867532Z","end":"2026-03-13T23:03:02.654026Z","steps":["trace[914878831] 'process raft request' (duration: 786.194405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:02.654571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:01.86751Z","time spent":"786.984053ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:03.660849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.729615ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-13T23:03:03.661009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:02.655079Z","time spent":"1.005916481s","remote":"127.0.0.1:43146","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T23:03:03.661149Z","caller":"traceutil/trace.go:171","msg":"trace[344720934] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"541.963299ms","start":"2026-03-13T23:03:03.119166Z","end":"2026-03-13T23:03:03.661129Z","steps":["trace[344720934] 'process raft request' (duration: 541.800585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:03.661259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.119144Z","time spent":"542.04744ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:03.661451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.227248ms","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-13T23:03:03.661568Z","caller":"traceutil/trace.go:171","msg":"trace[1773472442] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:891; }","duration":"624.404573ms","start":"2026-03-13T23:03:03.037141Z","end":"2026-03-13T23:03:03.661545Z","steps":["trace[1773472442] 'agreement among raft nodes before linearized reading' (duration: 624.019144ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:03.661779Z","caller":"traceutil/trace.go:171","msg":"trace[679779915] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"623.922431ms","start":"2026-03-13T23:03:03.037176Z","end":"2026-03-13T23:03:03.661098Z","steps":["trace[679779915] 'read index received' (duration: 61.471µs)","trace[679779915] 'applied index is now lower than readState.Index' (duration: 623.85142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:03.662168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.209713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-13T23:03:03.661631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.037122Z","time spent":"624.496545ms","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:03:03.662287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.239652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:03.662212Z","caller":"traceutil/trace.go:171","msg":"trace[1852218025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"396.311285ms","start":"2026-03-13T23:03:03.265886Z","end":"2026-03-13T23:03:03.662198Z","steps":["trace[1852218025] 'agreement among raft nodes before linearized reading' (duration: 396.167792ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:03.66232Z","caller":"traceutil/trace.go:171","msg":"trace[1183579293] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:891; }","duration":"127.309294ms","start":"2026-03-13T23:03:03.535Z","end":"2026-03-13T23:03:03.662309Z","steps":["trace[1183579293] 'agreement among raft nodes before linearized reading' (duration: 127.258603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:03.663307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.390634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-13T23:03:03.663374Z","caller":"traceutil/trace.go:171","msg":"trace[1262052078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"585.524167ms","start":"2026-03-13T23:03:03.077837Z","end":"2026-03-13T23:03:03.663362Z","steps":["trace[1262052078] 'agreement among raft nodes before linearized reading' (duration: 584.086972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:03.663329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.26586Z","time spent":"397.428502ms","remote":"127.0.0.1:43378","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-13T23:03:03.663458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.077813Z","time spent":"585.592489ms","remote":"127.0.0.1:43378","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-13T23:03:03.663681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.78185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-13T23:03:03.663346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.907728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-13T23:03:03.663846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.31998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:03.663864Z","caller":"traceutil/trace.go:171","msg":"trace[1006370455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"397.999606ms","start":"2026-03-13T23:03:03.265818Z","end":"2026-03-13T23:03:03.663818Z","steps":["trace[1006370455] 'agreement among raft nodes before linearized reading' (duration: 396.536411ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:03.663949Z","caller":"traceutil/trace.go:171","msg":"trace[620062952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"584.481451ms","start":"2026-03-13T23:03:03.079396Z","end":"2026-03-13T23:03:03.663878Z","steps":["trace[620062952] 'agreement among raft nodes before linearized reading' (duration: 583.498378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:03.66423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.265797Z","time spent":"398.20492ms","remote":"127.0.0.1:43378","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-13T23:03:03.664239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.079381Z","time spent":"584.84058ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:03:03.663914Z","caller":"traceutil/trace.go:171","msg":"trace[1734520473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"491.440043ms","start":"2026-03-13T23:03:03.172453Z","end":"2026-03-13T23:03:03.663893Z","steps":["trace[1734520473] 'agreement among raft nodes before linearized reading' (duration: 489.846115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:03.664404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:03.172431Z","time spent":"491.912454ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:03:09.943977Z","caller":"traceutil/trace.go:171","msg":"trace[1174733984] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"150.88869ms","start":"2026-03-13T23:03:09.793046Z","end":"2026-03-13T23:03:09.943934Z","steps":["trace[1174733984] 'process raft request' (duration: 136.223167ms)","trace[1174733984] 'compare' (duration: 14.506579ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:10.874858Z","caller":"traceutil/trace.go:171","msg":"trace[1888794004] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"121.567624ms","start":"2026-03-13T23:03:10.753267Z","end":"2026-03-13T23:03:10.874834Z","steps":["trace[1888794004] 'process raft request' (duration: 121.079623ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:11.888854Z","caller":"traceutil/trace.go:171","msg":"trace[1819179846] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"146.173346ms","start":"2026-03-13T23:03:11.742644Z","end":"2026-03-13T23:03:11.888817Z","steps":["trace[1819179846] 'process raft request' (duration: 145.862978ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:12.87801Z","caller":"traceutil/trace.go:171","msg":"trace[231838090] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"209.304214ms","start":"2026-03-13T23:03:12.668684Z","end":"2026-03-13T23:03:12.877988Z","steps":["trace[231838090] 'process raft request' (duration: 151.380931ms)","trace[231838090] 'compare' (duration: 57.819771ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:17.261159Z","caller":"traceutil/trace.go:171","msg":"trace[215817364] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"341.67928ms","start":"2026-03-13T23:03:16.919458Z","end":"2026-03-13T23:03:17.261138Z","steps":["trace[215817364] 'process raft request' (duration: 341.22659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:17.261305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:16.91941Z","time spent":"341.814863ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:18.6123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.443557ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-03-13T23:03:22.860978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.043645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-d24zf\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-13T23:03:22.861102Z","caller":"traceutil/trace.go:171","msg":"trace[1918693435] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-d24zf; range_end:; response_count:1; response_revision:962; }","duration":"127.210849ms","start":"2026-03-13T23:03:22.733848Z","end":"2026-03-13T23:03:22.861059Z","steps":["trace[1918693435] 'range keys from in-memory index tree' (duration: 126.80713ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:23.028214Z","caller":"traceutil/trace.go:171","msg":"trace[1449696333] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"111.072673ms","start":"2026-03-13T23:03:22.917115Z","end":"2026-03-13T23:03:23.028187Z","steps":["trace[1449696333] 'process raft request' (duration: 46.604532ms)","trace[1449696333] 'compare' (duration: 64.332737ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:23.039771Z","caller":"traceutil/trace.go:171","msg":"trace[1062911028] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"121.76198ms","start":"2026-03-13T23:03:22.917996Z","end":"2026-03-13T23:03:23.039758Z","steps":["trace[1062911028] 'process raft request' (duration: 121.627876ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:23.039864Z","caller":"traceutil/trace.go:171","msg":"trace[2085650874] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"116.36699ms","start":"2026-03-13T23:03:22.923487Z","end":"2026-03-13T23:03:23.039854Z","steps":["trace[2085650874] 'process raft request' (duration: 116.229947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.382486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068728951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:03:24.382582Z","caller":"traceutil/trace.go:171","msg":"trace[2065841470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.068873694s","start":"2026-03-13T23:03:23.313684Z","end":"2026-03-13T23:03:24.382558Z","steps":["trace[2065841470] 'range keys from in-memory index tree' (duration: 1.068529357s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.382628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:23.313665Z","time spent":"1.068953367s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:24.3828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209630191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:24.38289Z","caller":"traceutil/trace.go:171","msg":"trace[1242374262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"1.209772575s","start":"2026-03-13T23:03:23.173095Z","end":"2026-03-13T23:03:24.382868Z","steps":["trace[1242374262] 'range keys from in-memory index tree' (duration: 1.209510909s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.382951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:23.17307Z","time spent":"1.209872307s","remote":"127.0.0.1:43120","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-03-13T23:03:24.38307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.22612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:24.383181Z","caller":"traceutil/trace.go:171","msg":"trace[866569202] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"371.606ms","start":"2026-03-13T23:03:24.011557Z","end":"2026-03-13T23:03:24.383163Z","steps":["trace[866569202] 'read index received' (duration: 37.271µs)","trace[866569202] 'applied index is now lower than readState.Index' (duration: 371.567399ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:24.38321Z","caller":"traceutil/trace.go:171","msg":"trace[1736982735] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"464.513155ms","start":"2026-03-13T23:03:23.918681Z","end":"2026-03-13T23:03:24.383194Z","steps":["trace[1736982735] 'process raft request' (duration: 81.07456ms)","trace[1736982735] 'compare' (duration: 382.962364ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:24.383318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:23.918655Z","time spent":"464.597288ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:24.383398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.057661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-13T23:03:24.383392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.577521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T23:03:24.383435Z","caller":"traceutil/trace.go:171","msg":"trace[1829673692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"367.133313ms","start":"2026-03-13T23:03:24.016292Z","end":"2026-03-13T23:03:24.383426Z","steps":["trace[1829673692] 'agreement among raft nodes before linearized reading' (duration: 366.978879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.383476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:24.016272Z","time spent":"367.196514ms","remote":"127.0.0.1:43378","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-13T23:03:24.383481Z","caller":"traceutil/trace.go:171","msg":"trace[753313586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"258.700374ms","start":"2026-03-13T23:03:24.12476Z","end":"2026-03-13T23:03:24.38346Z","steps":["trace[753313586] 'agreement among raft nodes before linearized reading' (duration: 258.514739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.383516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.3596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-13T23:03:24.383576Z","caller":"traceutil/trace.go:171","msg":"trace[1666504585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"328.460902ms","start":"2026-03-13T23:03:24.055102Z","end":"2026-03-13T23:03:24.383563Z","steps":["trace[1666504585] 'agreement among raft nodes before linearized reading' (duration: 328.259427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.383613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:24.055081Z","time spent":"328.523513ms","remote":"127.0.0.1:43378","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-13T23:03:24.383645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.097701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-13T23:03:24.383684Z","caller":"traceutil/trace.go:171","msg":"trace[293232239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"372.182053ms","start":"2026-03-13T23:03:24.011491Z","end":"2026-03-13T23:03:24.383674Z","steps":["trace[293232239] 'agreement among raft nodes before linearized reading' (duration: 371.723222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.383717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:24.011465Z","time spent":"372.243635ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:03:24.664229Z","caller":"traceutil/trace.go:171","msg":"trace[702787161] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"265.933508ms","start":"2026-03-13T23:03:24.398265Z","end":"2026-03-13T23:03:24.664199Z","steps":["trace[702787161] 'read index received' (duration: 265.607369ms)","trace[702787161] 'applied index is now lower than readState.Index' (duration: 324.938µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:24.664341Z","caller":"traceutil/trace.go:171","msg":"trace[1010432559] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"275.461118ms","start":"2026-03-13T23:03:24.388832Z","end":"2026-03-13T23:03:24.664293Z","steps":["trace[1010432559] 'process raft request' (duration: 275.15376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:24.664412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.118162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:24.664501Z","caller":"traceutil/trace.go:171","msg":"trace[1169075674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"266.239995ms","start":"2026-03-13T23:03:24.398245Z","end":"2026-03-13T23:03:24.664485Z","steps":["trace[1169075674] 'agreement among raft nodes before linearized reading' (duration: 266.084442ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:24.685793Z","caller":"traceutil/trace.go:171","msg":"trace[1475582600] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"287.307512ms","start":"2026-03-13T23:03:24.398465Z","end":"2026-03-13T23:03:24.685773Z","steps":["trace[1475582600] 'process raft request' (duration: 287.092527ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:24.68584Z","caller":"traceutil/trace.go:171","msg":"trace[2043913496] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"286.900622ms","start":"2026-03-13T23:03:24.398908Z","end":"2026-03-13T23:03:24.685808Z","steps":["trace[2043913496] 'process raft request' (duration: 286.785679ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:24.686053Z","caller":"traceutil/trace.go:171","msg":"trace[1795622987] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"273.416807ms","start":"2026-03-13T23:03:24.412613Z","end":"2026-03-13T23:03:24.68603Z","steps":["trace[1795622987] 'process raft request' (duration: 273.11171ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:24.686344Z","caller":"traceutil/trace.go:171","msg":"trace[1507563070] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"287.69319ms","start":"2026-03-13T23:03:24.398638Z","end":"2026-03-13T23:03:24.686331Z","steps":["trace[1507563070] 'process raft request' (duration: 287.014804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:26.339368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.664168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:26.339672Z","caller":"traceutil/trace.go:171","msg":"trace[1851524802] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"312.95233ms","start":"2026-03-13T23:03:26.026697Z","end":"2026-03-13T23:03:26.339649Z","steps":["trace[1851524802] 'read index received' (duration: 57.192µs)","trace[1851524802] 'applied index is now lower than readState.Index' (duration: 312.893778ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:26.339794Z","caller":"traceutil/trace.go:171","msg":"trace[920456611] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"869.087689ms","start":"2026-03-13T23:03:25.470694Z","end":"2026-03-13T23:03:26.339781Z","steps":["trace[920456611] 'process raft request' (duration: 868.825613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:26.339915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:25.470671Z","time spent":"869.167711ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:26.340118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.391804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:26.340157Z","caller":"traceutil/trace.go:171","msg":"trace[2139043395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"166.492057ms","start":"2026-03-13T23:03:26.173653Z","end":"2026-03-13T23:03:26.340145Z","steps":["trace[2139043395] 'agreement among raft nodes before linearized reading' (duration: 166.426105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:26.340186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.723562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:26.340299Z","caller":"traceutil/trace.go:171","msg":"trace[1682956220] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:979; }","duration":"260.906017ms","start":"2026-03-13T23:03:26.079365Z","end":"2026-03-13T23:03:26.340272Z","steps":["trace[1682956220] 'agreement among raft nodes before linearized reading' (duration: 260.693912ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:26.340123Z","caller":"traceutil/trace.go:171","msg":"trace[382641337] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"871.298372ms","start":"2026-03-13T23:03:25.468771Z","end":"2026-03-13T23:03:26.34007Z","steps":["trace[382641337] 'process raft request' (duration: 519.826725ms)","trace[382641337] 'compare' (duration: 350.478112ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:26.340659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:25.468754Z","time spent":"871.796803ms","remote":"127.0.0.1:43186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:03:26.340881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.068516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:26.341358Z","caller":"traceutil/trace.go:171","msg":"trace[748145929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"314.698661ms","start":"2026-03-13T23:03:26.026629Z","end":"2026-03-13T23:03:26.341328Z","steps":["trace[748145929] 'agreement among raft nodes before linearized reading' (duration: 313.436581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:26.341426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.0266Z","time spent":"314.808644ms","remote":"127.0.0.1:43110","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-03-13T23:03:27.236249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.74099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-13T23:03:27.236323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.537246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:03:27.236361Z","caller":"traceutil/trace.go:171","msg":"trace[1558705436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"545.904204ms","start":"2026-03-13T23:03:26.690434Z","end":"2026-03-13T23:03:27.236338Z","steps":["trace[1558705436] 'range keys from in-memory index tree' (duration: 545.527305ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:27.236376Z","caller":"traceutil/trace.go:171","msg":"trace[830167859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"545.654678ms","start":"2026-03-13T23:03:26.690704Z","end":"2026-03-13T23:03:27.236359Z","steps":["trace[830167859] 'range keys from in-memory index tree' (duration: 545.388492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:27.236409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.690404Z","time spent":"545.993796ms","remote":"127.0.0.1:43378","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-13T23:03:27.236427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.690682Z","time spent":"545.724469ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:27.236642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.235103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-13T23:03:27.236717Z","caller":"traceutil/trace.go:171","msg":"trace[1057209102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"544.351086ms","start":"2026-03-13T23:03:26.692348Z","end":"2026-03-13T23:03:27.236699Z","steps":["trace[1057209102] 'range keys from in-memory index tree' (duration: 544.028389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:27.236747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.692334Z","time spent":"544.405577ms","remote":"127.0.0.1:43378","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-13T23:03:27.236722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.069734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:03:27.236861Z","caller":"traceutil/trace.go:171","msg":"trace[620846186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"545.229537ms","start":"2026-03-13T23:03:26.691561Z","end":"2026-03-13T23:03:27.236791Z","steps":["trace[620846186] 'range keys from in-memory index tree' (duration: 544.769577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:27.236912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.691539Z","time spent":"545.361701ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:27.237622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.373774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:03:27.237714Z","caller":"traceutil/trace.go:171","msg":"trace[1728371320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"568.538889ms","start":"2026-03-13T23:03:26.669153Z","end":"2026-03-13T23:03:27.237691Z","steps":["trace[1728371320] 'range keys from in-memory index tree' (duration: 568.037707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:27.237757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:26.669122Z","time spent":"568.62494ms","remote":"127.0.0.1:43378","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-13T23:03:27.426901Z","caller":"traceutil/trace.go:171","msg":"trace[2065849787] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"184.97056ms","start":"2026-03-13T23:03:27.241903Z","end":"2026-03-13T23:03:27.426873Z","steps":["trace[2065849787] 'process raft request' (duration: 160.665745ms)","trace[2065849787] 'compare' (duration: 24.141931ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:27.42729Z","caller":"traceutil/trace.go:171","msg":"trace[193687147] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"184.764025ms","start":"2026-03-13T23:03:27.242506Z","end":"2026-03-13T23:03:27.427271Z","steps":["trace[193687147] 'process raft request' (duration: 184.326154ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:27.427561Z","caller":"traceutil/trace.go:171","msg":"trace[1124897721] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"184.375305ms","start":"2026-03-13T23:03:27.243179Z","end":"2026-03-13T23:03:27.427554Z","steps":["trace[1124897721] 'process raft request' (duration: 184.025047ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:27.427682Z","caller":"traceutil/trace.go:171","msg":"trace[621574350] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"183.830833ms","start":"2026-03-13T23:03:27.243835Z","end":"2026-03-13T23:03:27.427666Z","steps":["trace[621574350] 'process raft request' (duration: 183.68874ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:29.813005Z","caller":"traceutil/trace.go:171","msg":"trace[737953153] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"325.528302ms","start":"2026-03-13T23:03:29.487454Z","end":"2026-03-13T23:03:29.812983Z","steps":["trace[737953153] 'process raft request' (duration: 325.338077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:29.813153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:29.487425Z","time spent":"325.648256ms","remote":"127.0.0.1:43280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:03:29.816478Z","caller":"traceutil/trace.go:171","msg":"trace[761054825] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"326.563247ms","start":"2026-03-13T23:03:29.489891Z","end":"2026-03-13T23:03:29.816454Z","steps":["trace[761054825] 'process raft request' (duration: 326.110596ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:29.816767Z","caller":"traceutil/trace.go:171","msg":"trace[850391583] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"326.809103ms","start":"2026-03-13T23:03:29.489941Z","end":"2026-03-13T23:03:29.81675Z","steps":["trace[850391583] 'process raft request' (duration: 326.435264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:29.816894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:29.489923Z","time spent":"326.915236ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:03:29.817047Z","caller":"traceutil/trace.go:171","msg":"trace[622593265] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"326.66732ms","start":"2026-03-13T23:03:29.490342Z","end":"2026-03-13T23:03:29.817009Z","steps":["trace[622593265] 'process raft request' (duration: 326.551047ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:29.817085Z","caller":"traceutil/trace.go:171","msg":"trace[833678932] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"327.042138ms","start":"2026-03-13T23:03:29.489977Z","end":"2026-03-13T23:03:29.817019Z","steps":["trace[833678932] 'process raft request' (duration: 326.652129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:29.817245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:29.490331Z","time spent":"326.810723ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:29.817367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:29.489961Z","time spent":"327.224633ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:29.817606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:29.489878Z","time spent":"326.892965ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:03:31.450403Z","caller":"traceutil/trace.go:171","msg":"trace[144606876] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"105.489528ms","start":"2026-03-13T23:03:31.344887Z","end":"2026-03-13T23:03:31.450376Z","steps":["trace[144606876] 'read index received' (duration: 105.17782ms)","trace[144606876] 'applied index is now lower than readState.Index' (duration: 310.768µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:31.450583Z","caller":"traceutil/trace.go:171","msg":"trace[477550098] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"113.642034ms","start":"2026-03-13T23:03:31.336907Z","end":"2026-03-13T23:03:31.450549Z","steps":["trace[477550098] 'process raft request' (duration: 113.262485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:31.450673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.757745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-13T23:03:31.450716Z","caller":"traceutil/trace.go:171","msg":"trace[509403985] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"105.841026ms","start":"2026-03-13T23:03:31.344862Z","end":"2026-03-13T23:03:31.450703Z","steps":["trace[509403985] 'agreement among raft nodes before linearized reading' (duration: 105.615111ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.021134Z","caller":"traceutil/trace.go:171","msg":"trace[1700733200] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"196.392355ms","start":"2026-03-13T23:03:31.824716Z","end":"2026-03-13T23:03:32.021109Z","steps":["trace[1700733200] 'process raft request' (duration: 196.215661ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.025112Z","caller":"traceutil/trace.go:171","msg":"trace[126936438] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"199.517089ms","start":"2026-03-13T23:03:31.825579Z","end":"2026-03-13T23:03:32.025096Z","steps":["trace[126936438] 'process raft request' (duration: 199.495359ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.025142Z","caller":"traceutil/trace.go:171","msg":"trace[2113977985] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"200.015072ms","start":"2026-03-13T23:03:31.825106Z","end":"2026-03-13T23:03:32.025121Z","steps":["trace[2113977985] 'process raft request' (duration: 199.882089ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.025201Z","caller":"traceutil/trace.go:171","msg":"trace[1867555440] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"199.691014ms","start":"2026-03-13T23:03:31.825462Z","end":"2026-03-13T23:03:32.025153Z","steps":["trace[1867555440] 'process raft request' (duration: 199.595071ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:35.903461Z","caller":"traceutil/trace.go:171","msg":"trace[727545604] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"284.398933ms","start":"2026-03-13T23:03:35.619032Z","end":"2026-03-13T23:03:35.903431Z","steps":["trace[727545604] 'process raft request' (duration: 284.181757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.357033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.525018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:36.357147Z","caller":"traceutil/trace.go:171","msg":"trace[587585842] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"330.635445ms","start":"2026-03-13T23:03:36.026492Z","end":"2026-03-13T23:03:36.357128Z","steps":["trace[587585842] 'read index received' (duration: 116.860311ms)","trace[587585842] 'applied index is now lower than readState.Index' (duration: 213.773723ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:36.357247Z","caller":"traceutil/trace.go:171","msg":"trace[457375135] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"448.136841ms","start":"2026-03-13T23:03:35.909099Z","end":"2026-03-13T23:03:36.357236Z","steps":["trace[457375135] 'process raft request' (duration: 234.313667ms)","trace[457375135] 'compare' (duration: 213.409913ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:36.357323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:35.909081Z","time spent":"448.198832ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:36.357381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.165261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:36.357463Z","caller":"traceutil/trace.go:171","msg":"trace[1248430262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"184.303454ms","start":"2026-03-13T23:03:36.17314Z","end":"2026-03-13T23:03:36.357443Z","steps":["trace[1248430262] 'agreement among raft nodes before linearized reading' (duration: 184.166821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.357586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.067134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:36.357705Z","caller":"traceutil/trace.go:171","msg":"trace[755824556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"331.242079ms","start":"2026-03-13T23:03:36.026434Z","end":"2026-03-13T23:03:36.357677Z","steps":["trace[755824556] 'agreement among raft nodes before linearized reading' (duration: 331.006123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.357782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.05656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-13T23:03:36.357837Z","caller":"traceutil/trace.go:171","msg":"trace[1569982530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"283.134841ms","start":"2026-03-13T23:03:36.074691Z","end":"2026-03-13T23:03:36.357826Z","steps":["trace[1569982530] 'agreement among raft nodes before linearized reading' (duration: 282.976007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.357823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.026413Z","time spent":"331.368891ms","remote":"127.0.0.1:43110","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-03-13T23:03:36.358016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.756347ms","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-13T23:03:36.358051Z","caller":"traceutil/trace.go:171","msg":"trace[1569262729] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1040; }","duration":"207.816759ms","start":"2026-03-13T23:03:36.150226Z","end":"2026-03-13T23:03:36.358043Z","steps":["trace[1569262729] 'agreement among raft nodes before linearized reading' (duration: 207.753018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.358225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.425086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-13T23:03:36.358333Z","caller":"traceutil/trace.go:171","msg":"trace[54427895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1040; }","duration":"312.600061ms","start":"2026-03-13T23:03:36.04571Z","end":"2026-03-13T23:03:36.35831Z","steps":["trace[54427895] 'agreement among raft nodes before linearized reading' (duration: 311.589157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.358383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.045692Z","time spent":"312.681643ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:36.358592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.985141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:03:36.358632Z","caller":"traceutil/trace.go:171","msg":"trace[1026887015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1040; }","duration":"306.072133ms","start":"2026-03-13T23:03:36.052551Z","end":"2026-03-13T23:03:36.358623Z","steps":["trace[1026887015] 'agreement among raft nodes before linearized reading' (duration: 305.969681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.35866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.052531Z","time spent":"306.122874ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:36.358681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.116804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:03:36.358729Z","caller":"traceutil/trace.go:171","msg":"trace[1006923191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1040; }","duration":"306.221776ms","start":"2026-03-13T23:03:36.052493Z","end":"2026-03-13T23:03:36.358715Z","steps":["trace[1006923191] 'agreement among raft nodes before linearized reading' (duration: 306.043532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:36.3589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.05246Z","time spent":"306.428471ms","remote":"127.0.0.1:43378","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-13T23:03:36.962039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724900460984570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:03:37.501492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.984858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:37.501674Z","caller":"traceutil/trace.go:171","msg":"trace[1543040] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"1.138184763s","start":"2026-03-13T23:03:36.363445Z","end":"2026-03-13T23:03:37.50163Z","steps":["trace[1543040] 'process raft request' (duration: 605.946098ms)","trace[1543040] 'compare' (duration: 531.801494ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:37.501747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.363424Z","time spent":"1.138290625s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:03:37.503334Z","caller":"traceutil/trace.go:171","msg":"trace[1704454372] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.139040003s","start":"2026-03-13T23:03:36.364262Z","end":"2026-03-13T23:03:37.503302Z","steps":["trace[1704454372] 'process raft request' (duration: 1.138705445s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.503682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.364193Z","time spent":"1.139234058s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:03:37.504009Z","caller":"traceutil/trace.go:171","msg":"trace[230495884] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1084; }","duration":"1.042347396s","start":"2026-03-13T23:03:36.461634Z","end":"2026-03-13T23:03:37.503982Z","steps":["trace[230495884] 'read index received' (duration: 507.774536ms)","trace[230495884] 'applied index is now lower than readState.Index' (duration: 534.57167ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:37.504161Z","caller":"traceutil/trace.go:171","msg":"trace[1404532534] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.139603207s","start":"2026-03-13T23:03:36.364538Z","end":"2026-03-13T23:03:37.504141Z","steps":["trace[1404532534] 'process raft request' (duration: 1.139350551s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.504285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.364525Z","time spent":"1.139666858s","remote":"127.0.0.1:43378","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-03-13T23:03:37.504773Z","caller":"traceutil/trace.go:171","msg":"trace[2101913056] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.139649368s","start":"2026-03-13T23:03:36.364354Z","end":"2026-03-13T23:03:37.504003Z","steps":["trace[2101913056] 'process raft request' (duration: 1.138872829s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.504838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.047035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:03:37.504961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.364319Z","time spent":"1.140561s","remote":"127.0.0.1:43378","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-03-13T23:03:37.505071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.563035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-03-13T23:03:37.505193Z","caller":"traceutil/trace.go:171","msg":"trace[200225565] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1044; }","duration":"284.735799ms","start":"2026-03-13T23:03:37.220433Z","end":"2026-03-13T23:03:37.505169Z","steps":["trace[200225565] 'agreement among raft nodes before linearized reading' (duration: 284.35242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.505331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.783771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:37.505386Z","caller":"traceutil/trace.go:171","msg":"trace[2140481724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"331.895094ms","start":"2026-03-13T23:03:37.173474Z","end":"2026-03-13T23:03:37.505369Z","steps":["trace[2140481724] 'agreement among raft nodes before linearized reading' (duration: 331.780382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.50547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:37.173453Z","time spent":"332.004437ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:03:37.504895Z","caller":"traceutil/trace.go:171","msg":"trace[751152295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"366.139728ms","start":"2026-03-13T23:03:37.138736Z","end":"2026-03-13T23:03:37.504875Z","steps":["trace[751152295] 'agreement among raft nodes before linearized reading' (duration: 366.025855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.506227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:37.138714Z","time spent":"367.496391ms","remote":"127.0.0.1:43110","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-03-13T23:03:37.506912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045262377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-13T23:03:37.506969Z","caller":"traceutil/trace.go:171","msg":"trace[542787120] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1044; }","duration":"1.04539324s","start":"2026-03-13T23:03:36.461565Z","end":"2026-03-13T23:03:37.506958Z","steps":["trace[542787120] 'agreement among raft nodes before linearized reading' (duration: 1.042647504s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:37.507161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:36.461487Z","time spent":"1.045642936s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-13T23:04:16.768453Z","caller":"traceutil/trace.go:171","msg":"trace[608758129] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"324.063238ms","start":"2026-03-13T23:04:16.444355Z","end":"2026-03-13T23:04:16.768418Z","steps":["trace[608758129] 'process raft request' (duration: 323.921185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:16.768626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:16.444335Z","time spent":"324.204571ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:04:17.853599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.907226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:04:17.853706Z","caller":"traceutil/trace.go:171","msg":"trace[1112197509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"129.03785ms","start":"2026-03-13T23:04:17.724641Z","end":"2026-03-13T23:04:17.853679Z","steps":["trace[1112197509] 'agreement among raft nodes before linearized reading' (duration: 96.726476ms)","trace[1112197509] 'range keys from in-memory index tree' (duration: 32.011745ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:21.891788Z","caller":"traceutil/trace.go:171","msg":"trace[989524316] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"131.093048ms","start":"2026-03-13T23:04:21.760642Z","end":"2026-03-13T23:04:21.891735Z","steps":["trace[989524316] 'process raft request' (duration: 89.375749ms)","trace[989524316] 'compare' (duration: 41.493594ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:04:21.891948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.179083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-13T23:04:21.892339Z","caller":"traceutil/trace.go:171","msg":"trace[1983457501] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1222; }","duration":"119.621974ms","start":"2026-03-13T23:04:21.772702Z","end":"2026-03-13T23:04:21.892324Z","steps":["trace[1983457501] 'agreement among raft nodes before linearized reading' (duration: 119.121292ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:21.891674Z","caller":"traceutil/trace.go:171","msg":"trace[218363713] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"118.893437ms","start":"2026-03-13T23:04:21.772753Z","end":"2026-03-13T23:04:21.891647Z","steps":["trace[218363713] 'read index received' (duration: 77.25954ms)","trace[218363713] 'applied index is now lower than readState.Index' (duration: 41.632787ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:22.126367Z","caller":"traceutil/trace.go:171","msg":"trace[760260269] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"229.618746ms","start":"2026-03-13T23:04:21.89672Z","end":"2026-03-13T23:04:22.126339Z","steps":["trace[760260269] 'process raft request' (duration: 143.939105ms)","trace[760260269] 'compare' (duration: 85.477276ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:22.126735Z","caller":"traceutil/trace.go:171","msg":"trace[909269241] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"228.368937ms","start":"2026-03-13T23:04:21.898344Z","end":"2026-03-13T23:04:22.126713Z","steps":["trace[909269241] 'process raft request' (duration: 228.236234ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:22.126771Z","caller":"traceutil/trace.go:171","msg":"trace[1701652902] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"228.349607ms","start":"2026-03-13T23:04:21.898409Z","end":"2026-03-13T23:04:22.126759Z","steps":["trace[1701652902] 'process raft request' (duration: 228.266835ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:22.126883Z","caller":"traceutil/trace.go:171","msg":"trace[286998101] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"230.075998ms","start":"2026-03-13T23:04:21.896756Z","end":"2026-03-13T23:04:22.126832Z","steps":["trace[286998101] 'process raft request' (duration: 229.533485ms)"],"step_count":1}