{"level":"warn","ts":"2026-02-25T03:04:54.54442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T03:04:54.544516Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-02-25T03:04:54.544599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T03:04:54.54461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-25T03:04:54.544631Z","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-02-25T03:04:54.54546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-02-25T03:04:54.545543Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-02-25T03:04:54.552803Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.994184ms"} {"level":"info","ts":"2026-02-25T03:04:54.567027Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-02-25T03:04:54.567158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T03:04:54.567194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-02-25T03:04:54.567216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T03:04:54.567239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-02-25T03:04:54.5673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-02-25T03:04:54.584244Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T03:04:54.586708Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T03:04:54.588492Z","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-02-25T03:04:54.593056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T03:04:54.593563Z","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-02-25T03:04:54.593658Z","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-02-25T03:04:54.593676Z","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-02-25T03:04:54.5937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T03:04:54.596302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-02-25T03:04:54.596864Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-25T03:04:54.597977Z","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-02-25T03:04:54.598225Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T03:04:54.598309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T03:04:54.599157Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-25T03:04:54.599179Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-25T03:04:54.96749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T03:04:54.967555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T03:04:54.967591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-02-25T03:04:54.96761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-02-25T03:04:54.967622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-25T03:04:54.967644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-02-25T03:04:54.967671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-25T03:04:54.970198Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T03:04:54.970248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T03:04:54.970209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T03:04:54.970525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T03:04:54.970587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T03:04:54.970195Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:04:54.973968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T03:04:54.974334Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:04:54.974408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-02-25T03:04:54.974576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:04:54.974659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T03:05:22.410725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.657851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:05:22.410878Z","caller":"traceutil/trace.go:171","msg":"trace[1676329141] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"160.13005ms","start":"2026-02-25T03:05:22.250733Z","end":"2026-02-25T03:05:22.410863Z","steps":["trace[1676329141] 'process raft request' (duration: 39.214498ms)","trace[1676329141] 'compare' (duration: 100.506187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:05:32.671411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.394124ms","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-02-25T03:05:32.671497Z","caller":"traceutil/trace.go:171","msg":"trace[866552425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"197.526707ms","start":"2026-02-25T03:05:32.473945Z","end":"2026-02-25T03:05:32.671471Z","steps":["trace[866552425] 'range keys from in-memory index tree' (duration: 197.151117ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:33.070944Z","caller":"traceutil/trace.go:171","msg":"trace[1629264020] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"238.331248ms","start":"2026-02-25T03:05:32.832571Z","end":"2026-02-25T03:05:33.070902Z","steps":["trace[1629264020] 'read index received' (duration: 205.832955ms)","trace[1629264020] 'applied index is now lower than readState.Index' (duration: 32.497213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:05:33.07107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.478991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T03:05:33.071106Z","caller":"traceutil/trace.go:171","msg":"trace[1332371072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"238.560684ms","start":"2026-02-25T03:05:32.832534Z","end":"2026-02-25T03:05:33.071094Z","steps":["trace[1332371072] 'agreement among raft nodes before linearized reading' (duration: 238.481182ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:33.071062Z","caller":"traceutil/trace.go:171","msg":"trace[761084614] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"395.188092ms","start":"2026-02-25T03:05:32.675828Z","end":"2026-02-25T03:05:33.071016Z","steps":["trace[761084614] 'process raft request' (duration: 362.628027ms)","trace[761084614] 'compare' (duration: 32.315049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:05:33.071441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:32.675808Z","time spent":"395.345246ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:05:33.569119Z","caller":"traceutil/trace.go:171","msg":"trace[1175173546] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"450.045474ms","start":"2026-02-25T03:05:33.119046Z","end":"2026-02-25T03:05:33.569092Z","steps":["trace[1175173546] 'process raft request' (duration: 449.756048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:33.569262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:33.119012Z","time spent":"450.173316ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:05:34.571215Z","caller":"traceutil/trace.go:171","msg":"trace[2097694224] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"277.663305ms","start":"2026-02-25T03:05:34.293517Z","end":"2026-02-25T03:05:34.57118Z","steps":["trace[2097694224] 'process raft request' (duration: 277.521822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:35.057934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.428263ms","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-02-25T03:05:35.058033Z","caller":"traceutil/trace.go:171","msg":"trace[319047316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"305.599376ms","start":"2026-02-25T03:05:34.752413Z","end":"2026-02-25T03:05:35.058012Z","steps":["trace[319047316] 'range keys from in-memory index tree' (duration: 305.205906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:35.058081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:34.752389Z","time spent":"305.68312ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T03:05:40.600856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.099024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-25T03:05:40.600953Z","caller":"traceutil/trace.go:171","msg":"trace[955280353] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:565; }","duration":"199.212207ms","start":"2026-02-25T03:05:40.401718Z","end":"2026-02-25T03:05:40.60093Z","steps":["trace[955280353] 'range keys from in-memory index tree' (duration: 198.809699ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:53.273875Z","caller":"traceutil/trace.go:171","msg":"trace[910465006] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"170.361988ms","start":"2026-02-25T03:05:53.103487Z","end":"2026-02-25T03:05:53.273849Z","steps":["trace[910465006] 'process raft request' (duration: 170.198054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:54.246448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.221561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:05:54.247088Z","caller":"traceutil/trace.go:171","msg":"trace[113357627] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"969.139612ms","start":"2026-02-25T03:05:53.277927Z","end":"2026-02-25T03:05:54.247066Z","steps":["trace[113357627] 'process raft request' (duration: 758.857305ms)","trace[113357627] 'compare' (duration: 208.968395ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:54.24719Z","caller":"traceutil/trace.go:171","msg":"trace[537329051] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"660.38907ms","start":"2026-02-25T03:05:53.586748Z","end":"2026-02-25T03:05:54.247137Z","steps":["trace[537329051] 'read index received' (duration: 450.049302ms)","trace[537329051] 'applied index is now lower than readState.Index' (duration: 210.337157ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:54.2472Z","caller":"traceutil/trace.go:171","msg":"trace[992129279] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"968.403374ms","start":"2026-02-25T03:05:53.278726Z","end":"2026-02-25T03:05:54.24713Z","steps":["trace[992129279] 'process raft request' (duration: 967.868541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:54.247261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:53.277907Z","time spent":"969.237525ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:05:54.247369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:53.278719Z","time spent":"968.561089ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:05:54.249382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.630825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:05:54.249445Z","caller":"traceutil/trace.go:171","msg":"trace[908689200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"662.724938ms","start":"2026-02-25T03:05:53.586707Z","end":"2026-02-25T03:05:54.249432Z","steps":["trace[908689200] 'agreement among raft nodes before linearized reading' (duration: 660.549364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:54.249489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:53.586692Z","time spent":"662.79008ms","remote":"127.0.0.1:53182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T03:06:27.696268Z","caller":"traceutil/trace.go:171","msg":"trace[553178311] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"109.902582ms","start":"2026-02-25T03:06:27.58634Z","end":"2026-02-25T03:06:27.696243Z","steps":["trace[553178311] 'read index received' (duration: 109.776849ms)","trace[553178311] 'applied index is now lower than readState.Index' (duration: 124.633µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:27.696382Z","caller":"traceutil/trace.go:171","msg":"trace[2145544667] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"124.431141ms","start":"2026-02-25T03:06:27.571889Z","end":"2026-02-25T03:06:27.69632Z","steps":["trace[2145544667] 'process raft request' (duration: 124.243016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:27.696425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.061206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:27.696468Z","caller":"traceutil/trace.go:171","msg":"trace[1777488407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"110.165448ms","start":"2026-02-25T03:06:27.586291Z","end":"2026-02-25T03:06:27.696456Z","steps":["trace[1777488407] 'agreement among raft nodes before linearized reading' (duration: 110.084136ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:30.864801Z","caller":"traceutil/trace.go:171","msg":"trace[654084614] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"297.330361ms","start":"2026-02-25T03:06:30.567431Z","end":"2026-02-25T03:06:30.864761Z","steps":["trace[654084614] 'read index received' (duration: 297.030693ms)","trace[654084614] 'applied index is now lower than readState.Index' (duration: 297.688µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:30.864981Z","caller":"traceutil/trace.go:171","msg":"trace[552331805] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"300.262004ms","start":"2026-02-25T03:06:30.564671Z","end":"2026-02-25T03:06:30.864933Z","steps":["trace[552331805] 'process raft request' (duration: 299.807363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:30.865256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.789643ms","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-02-25T03:06:30.865555Z","caller":"traceutil/trace.go:171","msg":"trace[348437339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"297.941696ms","start":"2026-02-25T03:06:30.5674Z","end":"2026-02-25T03:06:30.865342Z","steps":["trace[348437339] 'agreement among raft nodes before linearized reading' (duration: 297.629138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:30.865668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.630888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:06:30.865715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.828315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T03:06:30.865742Z","caller":"traceutil/trace.go:171","msg":"trace[1253926799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"278.741761ms","start":"2026-02-25T03:06:30.586981Z","end":"2026-02-25T03:06:30.865723Z","steps":["trace[1253926799] 'agreement among raft nodes before linearized reading' (duration: 278.618938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:30.865748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:30.564636Z","time spent":"300.700845ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:06:30.865835Z","caller":"traceutil/trace.go:171","msg":"trace[1983486652] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:786; }","duration":"292.320317ms","start":"2026-02-25T03:06:30.573475Z","end":"2026-02-25T03:06:30.865795Z","steps":["trace[1983486652] 'agreement among raft nodes before linearized reading' (duration: 291.696172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:31.58328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.696859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:06:31.583426Z","caller":"traceutil/trace.go:171","msg":"trace[1315731134] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"663.742904ms","start":"2026-02-25T03:06:30.91966Z","end":"2026-02-25T03:06:31.583403Z","steps":["trace[1315731134] 'read index received' (duration: 319.716846ms)","trace[1315731134] 'applied index is now lower than readState.Index' (duration: 344.024568ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:31.583491Z","caller":"traceutil/trace.go:171","msg":"trace[1338122048] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"712.990223ms","start":"2026-02-25T03:06:30.87043Z","end":"2026-02-25T03:06:31.58342Z","steps":["trace[1338122048] 'process raft request' (duration: 369.023157ms)","trace[1338122048] 'compare' (duration: 343.405262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:31.583628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.424498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:06:31.583676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.00613ms","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-02-25T03:06:31.583685Z","caller":"traceutil/trace.go:171","msg":"trace[31845340] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:787; }","duration":"128.541432ms","start":"2026-02-25T03:06:31.455121Z","end":"2026-02-25T03:06:31.583663Z","steps":["trace[31845340] 'agreement among raft nodes before linearized reading' (duration: 128.435189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:31.583686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:30.870413Z","time spent":"713.167977ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:06:31.583733Z","caller":"traceutil/trace.go:171","msg":"trace[2075967509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"664.114813ms","start":"2026-02-25T03:06:30.919604Z","end":"2026-02-25T03:06:31.583718Z","steps":["trace[2075967509] 'agreement among raft nodes before linearized reading' (duration: 663.909268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:31.583769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:30.919577Z","time spent":"664.184825ms","remote":"127.0.0.1:53462","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-02-25T03:06:31.834025Z","caller":"traceutil/trace.go:171","msg":"trace[1323785549] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"241.53602ms","start":"2026-02-25T03:06:31.59244Z","end":"2026-02-25T03:06:31.833976Z","steps":["trace[1323785549] 'read index received' (duration: 220.13994ms)","trace[1323785549] 'applied index is now lower than readState.Index' (duration: 21.39376ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:31.834418Z","caller":"traceutil/trace.go:171","msg":"trace[584834272] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"243.522189ms","start":"2026-02-25T03:06:31.590857Z","end":"2026-02-25T03:06:31.834379Z","steps":["trace[584834272] 'process raft request' (duration: 221.74866ms)","trace[584834272] 'compare' (duration: 21.001431ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:31.834806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.326169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T03:06:31.834978Z","caller":"traceutil/trace.go:171","msg":"trace[1959824828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"242.527473ms","start":"2026-02-25T03:06:31.592388Z","end":"2026-02-25T03:06:31.834916Z","steps":["trace[1959824828] 'agreement among raft nodes before linearized reading' (duration: 241.95583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:31.835135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T03:06:31.835213Z","caller":"traceutil/trace.go:171","msg":"trace[369273550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"125.919877ms","start":"2026-02-25T03:06:31.709272Z","end":"2026-02-25T03:06:31.835192Z","steps":["trace[369273550] 'agreement among raft nodes before linearized reading' (duration: 125.644421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:33.661907Z","caller":"traceutil/trace.go:171","msg":"trace[797884855] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"784.886802ms","start":"2026-02-25T03:06:32.876986Z","end":"2026-02-25T03:06:33.661873Z","steps":["trace[797884855] 'process raft request' (duration: 784.651387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:33.662127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:32.876942Z","time spent":"785.066637ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:06:33.760966Z","caller":"traceutil/trace.go:171","msg":"trace[713623362] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"170.667886ms","start":"2026-02-25T03:06:33.59026Z","end":"2026-02-25T03:06:33.760928Z","steps":["trace[713623362] 'read index received' (duration: 71.387177ms)","trace[713623362] 'applied index is now lower than readState.Index' (duration: 99.278799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:33.761385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.075096ms","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-02-25T03:06:33.761515Z","caller":"traceutil/trace.go:171","msg":"trace[767096484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"171.272512ms","start":"2026-02-25T03:06:33.590193Z","end":"2026-02-25T03:06:33.761466Z","steps":["trace[767096484] 'agreement among raft nodes before linearized reading' (duration: 170.866492ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:34.110435Z","caller":"traceutil/trace.go:171","msg":"trace[700929657] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"189.327038ms","start":"2026-02-25T03:06:33.921066Z","end":"2026-02-25T03:06:34.110393Z","steps":["trace[700929657] 'process raft request' (duration: 107.723797ms)","trace[700929657] 'compare' (duration: 81.430956ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:34.162262Z","caller":"traceutil/trace.go:171","msg":"trace[1046827066] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"240.680469ms","start":"2026-02-25T03:06:33.921561Z","end":"2026-02-25T03:06:34.162241Z","steps":["trace[1046827066] 'process raft request' (duration: 240.568866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:35.791437Z","caller":"traceutil/trace.go:171","msg":"trace[827133098] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"119.086047ms","start":"2026-02-25T03:06:35.672319Z","end":"2026-02-25T03:06:35.791405Z","steps":["trace[827133098] 'process raft request' (duration: 118.927114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:36.058939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.977676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:06:36.059124Z","caller":"traceutil/trace.go:171","msg":"trace[428096101] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"136.88695ms","start":"2026-02-25T03:06:35.922212Z","end":"2026-02-25T03:06:36.059099Z","steps":["trace[428096101] 'read index received' (duration: 17.495423ms)","trace[428096101] 'applied index is now lower than readState.Index' (duration: 119.389197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:36.059338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.116795ms","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-02-25T03:06:36.059384Z","caller":"traceutil/trace.go:171","msg":"trace[588753937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"137.215727ms","start":"2026-02-25T03:06:35.922154Z","end":"2026-02-25T03:06:36.05937Z","steps":["trace[588753937] 'agreement among raft nodes before linearized reading' (duration: 137.026613ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:36.059437Z","caller":"traceutil/trace.go:171","msg":"trace[1985797564] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"237.336976ms","start":"2026-02-25T03:06:35.821788Z","end":"2026-02-25T03:06:36.059125Z","steps":["trace[1985797564] 'process raft request' (duration: 118.026782ms)","trace[1985797564] 'compare' (duration: 118.755531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:36.427666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.980699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:06:36.427814Z","caller":"traceutil/trace.go:171","msg":"trace[1086316761] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"311.950834ms","start":"2026-02-25T03:06:36.11584Z","end":"2026-02-25T03:06:36.427791Z","steps":["trace[1086316761] 'read index received' (duration: 166.641507ms)","trace[1086316761] 'applied index is now lower than readState.Index' (duration: 145.307637ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:36.42793Z","caller":"traceutil/trace.go:171","msg":"trace[177711707] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"362.060065ms","start":"2026-02-25T03:06:36.065784Z","end":"2026-02-25T03:06:36.427844Z","steps":["trace[177711707] 'process raft request' (duration: 216.786988ms)","trace[177711707] 'compare' (duration: 144.819715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:36.428108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.269152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T03:06:36.42811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.338546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T03:06:36.42817Z","caller":"traceutil/trace.go:171","msg":"trace[1237547706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"312.369554ms","start":"2026-02-25T03:06:36.115783Z","end":"2026-02-25T03:06:36.428153Z","steps":["trace[1237547706] 'agreement among raft nodes before linearized reading' (duration: 312.134188ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:36.428183Z","caller":"traceutil/trace.go:171","msg":"trace[1914803918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"260.459339ms","start":"2026-02-25T03:06:36.167705Z","end":"2026-02-25T03:06:36.428164Z","steps":["trace[1914803918] 'agreement among raft nodes before linearized reading' (duration: 260.284324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:36.428215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:36.065751Z","time spent":"362.328131ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:06:36.428255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:36.115755Z","time spent":"312.463997ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T03:06:36.616686Z","caller":"traceutil/trace.go:171","msg":"trace[70964259] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"179.853213ms","start":"2026-02-25T03:06:36.436796Z","end":"2026-02-25T03:06:36.616649Z","steps":["trace[70964259] 'process raft request' (duration: 179.612007ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:49.374091Z","caller":"traceutil/trace.go:171","msg":"trace[1264529846] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"589.942345ms","start":"2026-02-25T03:06:48.784127Z","end":"2026-02-25T03:06:49.374069Z","steps":["trace[1264529846] 'process raft request' (duration: 589.795532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:49.374322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:48.784112Z","time spent":"590.075769ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:06:49.967552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.955179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:49.967609Z","caller":"traceutil/trace.go:171","msg":"trace[1774623831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"383.079361ms","start":"2026-02-25T03:06:49.584517Z","end":"2026-02-25T03:06:49.967596Z","steps":["trace[1774623831] 'range keys from in-memory index tree' (duration: 382.837656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:49.967644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:49.584495Z","time spent":"383.134353ms","remote":"127.0.0.1:53172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T03:06:49.967756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.845677ms","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-02-25T03:06:49.967779Z","caller":"traceutil/trace.go:171","msg":"trace[1365311732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"108.895278ms","start":"2026-02-25T03:06:49.858877Z","end":"2026-02-25T03:06:49.967772Z","steps":["trace[1365311732] 'range keys from in-memory index tree' (duration: 108.720533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:02.738764Z","caller":"traceutil/trace.go:171","msg":"trace[1836054600] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"154.729473ms","start":"2026-02-25T03:07:02.584021Z","end":"2026-02-25T03:07:02.73875Z","steps":["trace[1836054600] 'read index received' (duration: 154.58196ms)","trace[1836054600] 'applied index is now lower than readState.Index' (duration: 146.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:02.738973Z","caller":"traceutil/trace.go:171","msg":"trace[1486988258] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"165.924441ms","start":"2026-02-25T03:07:02.573038Z","end":"2026-02-25T03:07:02.738963Z","steps":["trace[1486988258] 'process raft request' (duration: 165.604704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:02.739119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.090641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:07:02.739143Z","caller":"traceutil/trace.go:171","msg":"trace[1349378439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"155.155594ms","start":"2026-02-25T03:07:02.583981Z","end":"2026-02-25T03:07:02.739137Z","steps":["trace[1349378439] 'agreement among raft nodes before linearized reading' (duration: 155.103723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:06.938521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.563169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:07:06.93862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.027942ms","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-02-25T03:07:06.938631Z","caller":"traceutil/trace.go:171","msg":"trace[1021908916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"352.714643ms","start":"2026-02-25T03:07:06.585881Z","end":"2026-02-25T03:07:06.938595Z","steps":["trace[1021908916] 'range keys from in-memory index tree' (duration: 352.491848ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:06.938674Z","caller":"traceutil/trace.go:171","msg":"trace[1287551455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"182.119124ms","start":"2026-02-25T03:07:06.756538Z","end":"2026-02-25T03:07:06.938657Z","steps":["trace[1287551455] 'range keys from in-memory index tree' (duration: 181.855569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:06.938687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:06.58586Z","time spent":"352.816925ms","remote":"127.0.0.1:53182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T03:07:06.938809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.858222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T03:07:06.938884Z","caller":"traceutil/trace.go:171","msg":"trace[1743864078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"177.034867ms","start":"2026-02-25T03:07:06.761832Z","end":"2026-02-25T03:07:06.938867Z","steps":["trace[1743864078] 'range keys from in-memory index tree' (duration: 176.688059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:06.939018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.022181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T03:07:06.939121Z","caller":"traceutil/trace.go:171","msg":"trace[1535940310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"720.178004ms","start":"2026-02-25T03:07:06.218919Z","end":"2026-02-25T03:07:06.939097Z","steps":["trace[1535940310] 'range keys from in-memory index tree' (duration: 719.801916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:06.939172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:06.218897Z","time spent":"720.263646ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T03:07:07.325857Z","caller":"traceutil/trace.go:171","msg":"trace[453558204] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"380.531553ms","start":"2026-02-25T03:07:06.945041Z","end":"2026-02-25T03:07:07.325573Z","steps":["trace[453558204] 'process raft request' (duration: 380.317778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:07.325987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:06.945022Z","time spent":"380.893672ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:07.826534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.552289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:07:07.826736Z","caller":"traceutil/trace.go:171","msg":"trace[1361752700] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"373.708206ms","start":"2026-02-25T03:07:07.453004Z","end":"2026-02-25T03:07:07.826712Z","steps":["trace[1361752700] 'read index received' (duration: 46.551µs)","trace[1361752700] 'applied index is now lower than readState.Index' (duration: 373.660415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:07.826963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.953781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T03:07:07.82702Z","caller":"traceutil/trace.go:171","msg":"trace[40804574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"374.038282ms","start":"2026-02-25T03:07:07.452964Z","end":"2026-02-25T03:07:07.827002Z","steps":["trace[40804574] 'agreement among raft nodes before linearized reading' (duration: 373.816847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:07.827067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:07.452947Z","time spent":"374.107395ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T03:07:07.827453Z","caller":"traceutil/trace.go:171","msg":"trace[1938301537] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"878.564931ms","start":"2026-02-25T03:07:06.948861Z","end":"2026-02-25T03:07:07.827426Z","steps":["trace[1938301537] 'process raft request' (duration: 877.779393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:07.827471Z","caller":"traceutil/trace.go:171","msg":"trace[247913786] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"878.93614ms","start":"2026-02-25T03:07:06.948503Z","end":"2026-02-25T03:07:07.827439Z","steps":["trace[247913786] 'process raft request' (duration: 459.376188ms)","trace[247913786] 'compare' (duration: 418.424897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:07.827611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:06.948842Z","time spent":"878.689323ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:07.827638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:06.948454Z","time spent":"879.084594ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:07:08.561449Z","caller":"traceutil/trace.go:171","msg":"trace[1581179573] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"116.654866ms","start":"2026-02-25T03:07:08.444779Z","end":"2026-02-25T03:07:08.561434Z","steps":["trace[1581179573] 'process raft request' (duration: 116.568184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:10.3225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.224611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:07:10.322556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.85096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T03:07:10.322607Z","caller":"traceutil/trace.go:171","msg":"trace[482735599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"735.463186ms","start":"2026-02-25T03:07:09.587125Z","end":"2026-02-25T03:07:10.322588Z","steps":["trace[482735599] 'range keys from in-memory index tree' (duration: 735.118338ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:10.322636Z","caller":"traceutil/trace.go:171","msg":"trace[215575913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"466.973023ms","start":"2026-02-25T03:07:09.855645Z","end":"2026-02-25T03:07:10.322618Z","steps":["trace[215575913] 'range keys from in-memory index tree' (duration: 466.707717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:10.32265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:09.587099Z","time spent":"735.542588ms","remote":"127.0.0.1:53182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T03:07:10.322683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:09.855631Z","time spent":"467.043095ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T03:07:10.322931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.472839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:07:10.322995Z","caller":"traceutil/trace.go:171","msg":"trace[853579574] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:960; }","duration":"442.737805ms","start":"2026-02-25T03:07:09.880243Z","end":"2026-02-25T03:07:10.322981Z","steps":["trace[853579574] 'count revisions from in-memory index tree' (duration: 442.423318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:10.323006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.941815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T03:07:10.323029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:09.880233Z","time spent":"442.788905ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-25T03:07:10.323048Z","caller":"traceutil/trace.go:171","msg":"trace[319275210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"491.022297ms","start":"2026-02-25T03:07:09.832016Z","end":"2026-02-25T03:07:10.323039Z","steps":["trace[319275210] 'range keys from in-memory index tree' (duration: 490.619667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:10.323073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:09.831997Z","time spent":"491.071198ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T03:07:10.323159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.997183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T03:07:10.323148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.023443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T03:07:10.323197Z","caller":"traceutil/trace.go:171","msg":"trace[1562197226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"490.056504ms","start":"2026-02-25T03:07:09.833132Z","end":"2026-02-25T03:07:10.323188Z","steps":["trace[1562197226] 'range keys from in-memory index tree' (duration: 489.906961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:10.323228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:09.833122Z","time spent":"490.100735ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T03:07:10.323223Z","caller":"traceutil/trace.go:171","msg":"trace[1888076148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"103.171576ms","start":"2026-02-25T03:07:10.220034Z","end":"2026-02-25T03:07:10.323205Z","steps":["trace[1888076148] 'range keys from in-memory index tree' (duration: 102.803697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:10.585698Z","caller":"traceutil/trace.go:171","msg":"trace[1690493486] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"257.679354ms","start":"2026-02-25T03:07:10.327993Z","end":"2026-02-25T03:07:10.585672Z","steps":["trace[1690493486] 'process raft request' (duration: 257.636543ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:10.586073Z","caller":"traceutil/trace.go:171","msg":"trace[963437340] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"258.050872ms","start":"2026-02-25T03:07:10.327992Z","end":"2026-02-25T03:07:10.586043Z","steps":["trace[963437340] 'process raft request' (duration: 257.593082ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:10.586181Z","caller":"traceutil/trace.go:171","msg":"trace[367971461] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"258.519583ms","start":"2026-02-25T03:07:10.327619Z","end":"2026-02-25T03:07:10.586139Z","steps":["trace[367971461] 'process raft request' (duration: 257.829517ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:11.372607Z","caller":"traceutil/trace.go:171","msg":"trace[1285621477] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"381.665229ms","start":"2026-02-25T03:07:10.990918Z","end":"2026-02-25T03:07:11.372584Z","steps":["trace[1285621477] 'process raft request' (duration: 381.513175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:11.372729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:10.990899Z","time spent":"381.769772ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T03:07:12.09083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125952966318239663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T03:07:12.579687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.744901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:07:12.579803Z","caller":"traceutil/trace.go:171","msg":"trace[2028489523] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"989.910276ms","start":"2026-02-25T03:07:11.589874Z","end":"2026-02-25T03:07:12.579784Z","steps":["trace[2028489523] 'read index received' (duration: 571.960991ms)","trace[2028489523] 'applied index is now lower than readState.Index' (duration: 417.947605ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:12.579931Z","caller":"traceutil/trace.go:171","msg":"trace[1574992682] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.163986634s","start":"2026-02-25T03:07:11.415903Z","end":"2026-02-25T03:07:12.579889Z","steps":["trace[1574992682] 'process raft request' (duration: 745.953726ms)","trace[1574992682] 'compare' (duration: 417.636339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:12.580079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:11.415887Z","time spent":"1.164115357s","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:12.580073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.200352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:07:12.580101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.613519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T03:07:12.58017Z","caller":"traceutil/trace.go:171","msg":"trace[591800450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"990.299745ms","start":"2026-02-25T03:07:11.589824Z","end":"2026-02-25T03:07:12.580124Z","steps":["trace[591800450] 'agreement among raft nodes before linearized reading' (duration: 990.053049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:12.580183Z","caller":"traceutil/trace.go:171","msg":"trace[224845736] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:966; }","duration":"272.731411ms","start":"2026-02-25T03:07:12.307433Z","end":"2026-02-25T03:07:12.580165Z","steps":["trace[224845736] 'agreement among raft nodes before linearized reading' (duration: 272.586268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:12.580207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.121941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T03:07:12.580232Z","caller":"traceutil/trace.go:171","msg":"trace[1337968195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"184.183001ms","start":"2026-02-25T03:07:12.396042Z","end":"2026-02-25T03:07:12.580225Z","steps":["trace[1337968195] 'agreement among raft nodes before linearized reading' (duration: 184.085669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:12.580257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:11.589806Z","time spent":"990.403517ms","remote":"127.0.0.1:53182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T03:07:13.181299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.293191ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:07:13.181427Z","caller":"traceutil/trace.go:171","msg":"trace[627701946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:966; }","duration":"114.441944ms","start":"2026-02-25T03:07:13.066968Z","end":"2026-02-25T03:07:13.18141Z","steps":["trace[627701946] 'range keys from in-memory index tree' (duration: 114.27616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.181981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.19573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:07:13.182037Z","caller":"traceutil/trace.go:171","msg":"trace[507719355] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"592.519205ms","start":"2026-02-25T03:07:12.589509Z","end":"2026-02-25T03:07:13.182028Z","steps":["trace[507719355] 'read index received' (duration: 173.976777ms)","trace[507719355] 'applied index is now lower than readState.Index' (duration: 418.539818ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:13.182094Z","caller":"traceutil/trace.go:171","msg":"trace[917515531] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"593.545558ms","start":"2026-02-25T03:07:12.588515Z","end":"2026-02-25T03:07:13.182061Z","steps":["trace[917515531] 'process raft request' (duration: 175.02168ms)","trace[917515531] 'compare' (duration: 417.986725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:13.182185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.672578ms","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-02-25T03:07:13.182207Z","caller":"traceutil/trace.go:171","msg":"trace[220997505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"592.702749ms","start":"2026-02-25T03:07:12.589497Z","end":"2026-02-25T03:07:13.182199Z","steps":["trace[220997505] 'agreement among raft nodes before linearized reading' (duration: 592.590016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.182232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.589491Z","time spent":"592.734909ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T03:07:13.182254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.588498Z","time spent":"593.66991ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:13.182337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.760106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:07:13.182358Z","caller":"traceutil/trace.go:171","msg":"trace[993994162] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:967; }","duration":"267.808497ms","start":"2026-02-25T03:07:12.914543Z","end":"2026-02-25T03:07:13.182352Z","steps":["trace[993994162] 'agreement among raft nodes before linearized reading' (duration: 267.774467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.182376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.802941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T03:07:13.182438Z","caller":"traceutil/trace.go:171","msg":"trace[1742903028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"592.871773ms","start":"2026-02-25T03:07:12.589551Z","end":"2026-02-25T03:07:13.182422Z","steps":["trace[1742903028] 'agreement among raft nodes before linearized reading' (duration: 592.669568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.182466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.809411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T03:07:13.18247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.589528Z","time spent":"592.931594ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T03:07:13.182492Z","caller":"traceutil/trace.go:171","msg":"trace[1495917504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"592.864682ms","start":"2026-02-25T03:07:12.58962Z","end":"2026-02-25T03:07:13.182484Z","steps":["trace[1495917504] 'agreement among raft nodes before linearized reading' (duration: 592.792791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.182512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.589603Z","time spent":"592.905123ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T03:07:18.454839Z","caller":"traceutil/trace.go:171","msg":"trace[1849518644] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"105.14677ms","start":"2026-02-25T03:07:18.349677Z","end":"2026-02-25T03:07:18.454824Z","steps":["trace[1849518644] 'process raft request' (duration: 105.019668ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:20.899979Z","caller":"traceutil/trace.go:171","msg":"trace[1300875613] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"271.560693ms","start":"2026-02-25T03:07:20.628395Z","end":"2026-02-25T03:07:20.899956Z","steps":["trace[1300875613] 'process raft request' (duration: 271.378369ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:22.732034Z","caller":"traceutil/trace.go:171","msg":"trace[886406169] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"145.765517ms","start":"2026-02-25T03:07:22.586238Z","end":"2026-02-25T03:07:22.732004Z","steps":["trace[886406169] 'process raft request' (duration: 145.47618ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:10:40.534599Z","caller":"traceutil/trace.go:171","msg":"trace[1713775989] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"113.714737ms","start":"2026-02-25T03:10:40.420854Z","end":"2026-02-25T03:10:40.534569Z","steps":["trace[1713775989] 'process raft request' (duration: 113.489882ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:10:40.599936Z","caller":"traceutil/trace.go:171","msg":"trace[328881310] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"178.361849ms","start":"2026-02-25T03:10:40.421552Z","end":"2026-02-25T03:10:40.599914Z","steps":["trace[328881310] 'process raft request' (duration: 177.435149ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:10:40.601045Z","caller":"traceutil/trace.go:171","msg":"trace[707613639] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"179.142987ms","start":"2026-02-25T03:10:40.421886Z","end":"2026-02-25T03:10:40.601029Z","steps":["trace[707613639] 'process raft request' (duration: 177.990851ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:10:40.601258Z","caller":"traceutil/trace.go:171","msg":"trace[1856243205] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"178.33541ms","start":"2026-02-25T03:10:40.422711Z","end":"2026-02-25T03:10:40.601046Z","steps":["trace[1856243205] 'process raft request' (duration: 178.064074ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:10:49.987292Z","caller":"traceutil/trace.go:171","msg":"trace[1602713565] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"327.749966ms","start":"2026-02-25T03:10:49.659514Z","end":"2026-02-25T03:10:49.987264Z","steps":["trace[1602713565] 'read index received' (duration: 327.565242ms)","trace[1602713565] 'applied index is now lower than readState.Index' (duration: 183.314µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:10:49.987601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.072603ms","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:535"} {"level":"info","ts":"2026-02-25T03:10:49.987649Z","caller":"traceutil/trace.go:171","msg":"trace[93829067] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1911; }","duration":"328.199776ms","start":"2026-02-25T03:10:49.659434Z","end":"2026-02-25T03:10:49.987634Z","steps":["trace[93829067] 'agreement among raft nodes before linearized reading' (duration: 327.973101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:10:49.987688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:10:49.659401Z","time spent":"328.279078ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T03:10:49.987927Z","caller":"traceutil/trace.go:171","msg":"trace[1634076389] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"361.195467ms","start":"2026-02-25T03:10:49.626635Z","end":"2026-02-25T03:10:49.98783Z","steps":["trace[1634076389] 'process raft request' (duration: 360.448821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:10:49.988178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:10:49.6266Z","time spent":"361.439853ms","remote":"127.0.0.1:53462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}