{"level":"warn","ts":"2026-04-13T19:05:15.3059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T19:05:15.306018Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-04-13T19:05:15.306174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T19:05:15.306186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-13T19:05:15.306305Z","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-04-13T19:05:15.307488Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-13T19:05:15.307584Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-04-13T19:05:15.400244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"92.379862ms"} {"level":"info","ts":"2026-04-13T19:05:15.558265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-13T19:05:15.558566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T19:05:15.558637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-13T19:05:15.558656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T19:05:15.558676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-13T19:05:15.558832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-13T19:05:15.748415Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T19:05:15.997119Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T19:05:16.025794Z","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-04-13T19:05:16.045861Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T19:05:16.048373Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T19:05:16.048696Z","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-04-13T19:05:16.05275Z","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-04-13T19:05:16.052801Z","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-04-13T19:05:16.055535Z","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-04-13T19:05:16.055821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-13T19:05:16.055848Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-13T19:05:16.056141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T19:05:16.056194Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T19:05:16.086604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-13T19:05:16.086769Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-13T19:05:16.364679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T19:05:16.364778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T19:05:16.364845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-13T19:05:16.364896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-13T19:05:16.364908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-13T19:05:16.364926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-13T19:05:16.364947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-13T19:05:16.37407Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T19:05:16.375593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T19:05:16.375611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T19:05:16.376112Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T19:05:16.376461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T19:05:16.376526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T19:05:16.377343Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T19:05:16.377539Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T19:05:16.37759Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T19:05:16.379536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T19:05:16.382066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"warn","ts":"2026-04-13T19:05:19.551395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.661561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T19:05:19.551641Z","caller":"traceutil/trace.go:171","msg":"trace[722677669] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"259.728244ms","start":"2026-04-13T19:05:19.291829Z","end":"2026-04-13T19:05:19.551557Z","steps":["trace[722677669] 'process raft request' (duration: 11.270844ms)","trace[722677669] 'compare' (duration: 247.409845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:05:21.573194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.691295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:05:21.573271Z","caller":"traceutil/trace.go:171","msg":"trace[1005694746] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:264; }","duration":"208.818268ms","start":"2026-04-13T19:05:21.364437Z","end":"2026-04-13T19:05:21.573255Z","steps":["trace[1005694746] 'range keys from in-memory index tree' (duration: 208.550062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:21.573481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.362626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:05:21.573498Z","caller":"traceutil/trace.go:171","msg":"trace[10568092] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:264; }","duration":"121.402206ms","start":"2026-04-13T19:05:21.452088Z","end":"2026-04-13T19:05:21.57349Z","steps":["trace[10568092] 'range keys from in-memory index tree' (duration: 121.317065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:21.57359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.120389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-13T19:05:21.573613Z","caller":"traceutil/trace.go:171","msg":"trace[1826598259] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:264; }","duration":"121.167761ms","start":"2026-04-13T19:05:21.452431Z","end":"2026-04-13T19:05:21.573598Z","steps":["trace[1826598259] 'range keys from in-memory index tree' (duration: 121.046568ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:21.573743Z","caller":"traceutil/trace.go:171","msg":"trace[657424098] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"120.894064ms","start":"2026-04-13T19:05:21.452843Z","end":"2026-04-13T19:05:21.573737Z","steps":["trace[657424098] 'process raft request' (duration: 110.254386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.076966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764697354055248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T19:05:22.351844Z","caller":"traceutil/trace.go:171","msg":"trace[1509875776] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"799.961955ms","start":"2026-04-13T19:05:21.551832Z","end":"2026-04-13T19:05:22.351794Z","steps":["trace[1509875776] 'process raft request' (duration: 770.752676ms)","trace[1509875776] 'compare' (duration: 29.152178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:05:22.352877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.551804Z","time spent":"800.135289ms","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T19:05:22.835348Z","caller":"traceutil/trace.go:171","msg":"trace[841541717] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"1.261811174s","start":"2026-04-13T19:05:21.57349Z","end":"2026-04-13T19:05:22.835301Z","steps":["trace[841541717] 'process raft request' (duration: 1.261533437s)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:22.835428Z","caller":"traceutil/trace.go:171","msg":"trace[30203136] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"1.261742882s","start":"2026-04-13T19:05:21.573659Z","end":"2026-04-13T19:05:22.835402Z","steps":["trace[30203136] 'process raft request' (duration: 1.261544717s)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:22.835471Z","caller":"traceutil/trace.go:171","msg":"trace[860528406] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:271; }","duration":"1.259425187s","start":"2026-04-13T19:05:21.57599Z","end":"2026-04-13T19:05:22.835415Z","steps":["trace[860528406] 'read index received' (duration: 746.57937ms)","trace[860528406] 'applied index is now lower than readState.Index' (duration: 512.844687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:05:22.835543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.573458Z","time spent":"1.262009719s","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T19:05:22.835612Z","caller":"traceutil/trace.go:171","msg":"trace[63025489] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"1.261841175s","start":"2026-04-13T19:05:21.573752Z","end":"2026-04-13T19:05:22.835593Z","steps":["trace[63025489] 'process raft request' (duration: 1.261537747s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.835611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.573654Z","time spent":"1.261870975s","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:22.835721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.573746Z","time spent":"1.261899336s","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T19:05:22.835775Z","caller":"traceutil/trace.go:171","msg":"trace[1406798345] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"1.261962128s","start":"2026-04-13T19:05:21.5738Z","end":"2026-04-13T19:05:22.835762Z","steps":["trace[1406798345] 'process raft request' (duration: 1.261536878s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.83583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.573794Z","time spent":"1.262013209s","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:22.835904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259874027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-13T19:05:22.835911Z","caller":"traceutil/trace.go:171","msg":"trace[43802290] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"1.259563599s","start":"2026-04-13T19:05:21.576338Z","end":"2026-04-13T19:05:22.835902Z","steps":["trace[43802290] 'process raft request' (duration: 1.259029476s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.835903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259862257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-13T19:05:22.835946Z","caller":"traceutil/trace.go:171","msg":"trace[1436732185] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:269; }","duration":"1.259941599s","start":"2026-04-13T19:05:21.575986Z","end":"2026-04-13T19:05:22.835927Z","steps":["trace[1436732185] 'agreement among raft nodes before linearized reading' (duration: 1.259865217s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.835957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.576328Z","time spent":"1.25960625s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:22.83598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.575972Z","time spent":"1.26000103s","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-13T19:05:22.835994Z","caller":"traceutil/trace.go:171","msg":"trace[510451636] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"1.26001505s","start":"2026-04-13T19:05:21.57595Z","end":"2026-04-13T19:05:22.835965Z","steps":["trace[510451636] 'agreement among raft nodes before linearized reading' (duration: 1.259751974s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.836048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.575939Z","time spent":"1.260100463s","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-13T19:05:22.836194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.012722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-13T19:05:22.836232Z","caller":"traceutil/trace.go:171","msg":"trace[2015788090] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"476.069854ms","start":"2026-04-13T19:05:22.360153Z","end":"2026-04-13T19:05:22.836222Z","steps":["trace[2015788090] 'agreement among raft nodes before linearized reading' (duration: 476.005203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.836259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.360136Z","time spent":"476.117015ms","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-13T19:05:22.836328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256458594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:05:22.836374Z","caller":"traceutil/trace.go:171","msg":"trace[1396173411] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:269; }","duration":"1.256553707s","start":"2026-04-13T19:05:21.57981Z","end":"2026-04-13T19:05:22.836364Z","steps":["trace[1396173411] 'agreement among raft nodes before linearized reading' (duration: 1.256468695s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.836405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.579788Z","time spent":"1.256610047s","remote":"127.0.0.1:51762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-13T19:05:22.836475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.583183ms","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-04-13T19:05:22.836514Z","caller":"traceutil/trace.go:171","msg":"trace[1949371866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:269; }","duration":"693.666395ms","start":"2026-04-13T19:05:22.142838Z","end":"2026-04-13T19:05:22.836504Z","steps":["trace[1949371866] 'agreement among raft nodes before linearized reading' (duration: 693.611264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.836519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051325675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T19:05:22.836578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.142811Z","time spent":"693.724797ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T19:05:22.836603Z","caller":"traceutil/trace.go:171","msg":"trace[902324103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:269; }","duration":"1.051445168s","start":"2026-04-13T19:05:21.785144Z","end":"2026-04-13T19:05:22.836589Z","steps":["trace[902324103] 'agreement among raft nodes before linearized reading' (duration: 1.051341616s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.836637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.785121Z","time spent":"1.05150853s","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T19:05:22.836707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.530874ms","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-04-13T19:05:22.836775Z","caller":"traceutil/trace.go:171","msg":"trace[853011649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:269; }","duration":"895.623546ms","start":"2026-04-13T19:05:21.941117Z","end":"2026-04-13T19:05:22.836741Z","steps":["trace[853011649] 'agreement among raft nodes before linearized reading' (duration: 895.542534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.83681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.941091Z","time spent":"895.712368ms","remote":"127.0.0.1:51936","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-04-13T19:05:22.836905Z","caller":"traceutil/trace.go:171","msg":"trace[229152588] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"1.261720492s","start":"2026-04-13T19:05:21.573611Z","end":"2026-04-13T19:05:22.835331Z","steps":["trace[229152588] 'process raft request' (duration: 1.261558368s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.837041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.573604Z","time spent":"1.263407882s","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:22.839308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.913494ms","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-04-13T19:05:22.839484Z","caller":"traceutil/trace.go:171","msg":"trace[1774237888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:269; }","duration":"957.307173ms","start":"2026-04-13T19:05:21.882137Z","end":"2026-04-13T19:05:22.839444Z","steps":["trace[1774237888] 'agreement among raft nodes before linearized reading' (duration: 954.117876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:22.839574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:21.882055Z","time spent":"957.499988ms","remote":"127.0.0.1:51936","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-04-13T19:05:23.180076Z","caller":"traceutil/trace.go:171","msg":"trace[1924382590] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"337.491901ms","start":"2026-04-13T19:05:22.842534Z","end":"2026-04-13T19:05:23.180026Z","steps":["trace[1924382590] 'read index received' (duration: 335.687007ms)","trace[1924382590] 'applied index is now lower than readState.Index' (duration: 1.803654ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:23.180424Z","caller":"traceutil/trace.go:171","msg":"trace[1638642157] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"337.88733ms","start":"2026-04-13T19:05:22.842471Z","end":"2026-04-13T19:05:23.180358Z","steps":["trace[1638642157] 'process raft request' (duration: 335.727518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.180663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.091746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-13T19:05:23.180756Z","caller":"traceutil/trace.go:171","msg":"trace[1798709453] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:270; }","duration":"338.245789ms","start":"2026-04-13T19:05:22.842488Z","end":"2026-04-13T19:05:23.180733Z","steps":["trace[1798709453] 'agreement among raft nodes before linearized reading' (duration: 337.956582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.180825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.842469Z","time spent":"338.335021ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-13T19:05:23.18086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.842439Z","time spent":"338.243289ms","remote":"127.0.0.1:51762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:23.185357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.977491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-13T19:05:23.185362Z","caller":"traceutil/trace.go:171","msg":"trace[1498937541] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"341.413916ms","start":"2026-04-13T19:05:22.843923Z","end":"2026-04-13T19:05:23.185337Z","steps":["trace[1498937541] 'process raft request' (duration: 341.094068ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:23.185446Z","caller":"traceutil/trace.go:171","msg":"trace[2016571251] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"339.950351ms","start":"2026-04-13T19:05:22.845485Z","end":"2026-04-13T19:05:23.185436Z","steps":["trace[2016571251] 'process raft request' (duration: 339.667224ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:23.18541Z","caller":"traceutil/trace.go:171","msg":"trace[2055044677] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:277; }","duration":"340.070984ms","start":"2026-04-13T19:05:22.845328Z","end":"2026-04-13T19:05:23.185399Z","steps":["trace[2055044677] 'agreement among raft nodes before linearized reading' (duration: 339.972661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.185508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.845472Z","time spent":"339.994291ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:23.18551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.845308Z","time spent":"340.191586ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-04-13T19:05:23.185585Z","caller":"traceutil/trace.go:171","msg":"trace[656281171] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"338.090975ms","start":"2026-04-13T19:05:22.847461Z","end":"2026-04-13T19:05:23.185552Z","steps":["trace[656281171] 'process raft request' (duration: 337.774948ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:23.185642Z","caller":"traceutil/trace.go:171","msg":"trace[590089179] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"335.069532ms","start":"2026-04-13T19:05:22.850538Z","end":"2026-04-13T19:05:23.185607Z","steps":["trace[590089179] 'process raft request' (duration: 334.723023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.185667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.843909Z","time spent":"341.535139ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T19:05:23.185691Z","caller":"traceutil/trace.go:171","msg":"trace[23536017] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"341.677703ms","start":"2026-04-13T19:05:22.844003Z","end":"2026-04-13T19:05:23.185681Z","steps":["trace[23536017] 'process raft request' (duration: 341.113699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.185746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.843999Z","time spent":"341.723434ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T19:05:23.185806Z","caller":"traceutil/trace.go:171","msg":"trace[1063341295] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"340.293549ms","start":"2026-04-13T19:05:22.845484Z","end":"2026-04-13T19:05:23.185777Z","steps":["trace[1063341295] 'process raft request' (duration: 339.695594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.185888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.845467Z","time spent":"340.37459ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:23.185886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.009984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:05:23.185936Z","caller":"traceutil/trace.go:171","msg":"trace[156717800] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"340.34516ms","start":"2026-04-13T19:05:22.845579Z","end":"2026-04-13T19:05:23.185924Z","steps":["trace[156717800] 'process raft request' (duration: 339.628053ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:23.185968Z","caller":"traceutil/trace.go:171","msg":"trace[656150846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:277; }","duration":"338.112566ms","start":"2026-04-13T19:05:22.847818Z","end":"2026-04-13T19:05:23.185931Z","steps":["trace[656150846] 'agreement among raft nodes before linearized reading' (duration: 337.930311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:23.186005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.847798Z","time spent":"338.196478ms","remote":"127.0.0.1:36110","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-04-13T19:05:23.186433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.847443Z","time spent":"338.239709ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:05:23.186513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.845569Z","time spent":"340.395371ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:05:23.186627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:22.850531Z","time spent":"335.183345ms","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:05:43.970099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764697354055841,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T19:05:44.001636Z","caller":"traceutil/trace.go:171","msg":"trace[1057068002] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"538.473479ms","start":"2026-04-13T19:05:43.463133Z","end":"2026-04-13T19:05:44.001607Z","steps":["trace[1057068002] 'process raft request' (duration: 538.11966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:44.00188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:43.463112Z","time spent":"538.598671ms","remote":"127.0.0.1:51728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:05:44.021875Z","caller":"traceutil/trace.go:171","msg":"trace[826390462] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"551.781792ms","start":"2026-04-13T19:05:43.470061Z","end":"2026-04-13T19:05:44.021843Z","steps":["trace[826390462] 'read index received' (duration: 531.08807ms)","trace[826390462] 'applied index is now lower than readState.Index' (duration: 20.692272ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:44.022044Z","caller":"traceutil/trace.go:171","msg":"trace[1258608898] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"458.587499ms","start":"2026-04-13T19:05:43.563441Z","end":"2026-04-13T19:05:44.022029Z","steps":["trace[1258608898] 'process raft request' (duration: 458.237711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:44.02218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:43.563411Z","time spent":"458.673222ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:05:44.022343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.900056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T19:05:44.02241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.50826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-13T19:05:44.022428Z","caller":"traceutil/trace.go:171","msg":"trace[1657796244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"173.04161ms","start":"2026-04-13T19:05:43.849367Z","end":"2026-04-13T19:05:44.022409Z","steps":["trace[1657796244] 'agreement among raft nodes before linearized reading' (duration: 172.903586ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:44.022445Z","caller":"traceutil/trace.go:171","msg":"trace[1646061867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:576; }","duration":"550.556362ms","start":"2026-04-13T19:05:43.471877Z","end":"2026-04-13T19:05:44.022434Z","steps":["trace[1646061867] 'agreement among raft nodes before linearized reading' (duration: 550.354807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:44.022488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:43.471871Z","time spent":"550.592643ms","remote":"127.0.0.1:51728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74792,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-13T19:05:44.022601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.491489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-13T19:05:44.022712Z","caller":"traceutil/trace.go:171","msg":"trace[479008715] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:576; }","duration":"552.644012ms","start":"2026-04-13T19:05:43.47004Z","end":"2026-04-13T19:05:44.022684Z","steps":["trace[479008715] 'agreement among raft nodes before linearized reading' (duration: 552.201132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:44.022771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:43.470033Z","time spent":"552.724334ms","remote":"127.0.0.1:51728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570128,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-13T19:05:50.433034Z","caller":"traceutil/trace.go:171","msg":"trace[1481079733] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"203.53773ms","start":"2026-04-13T19:05:50.229461Z","end":"2026-04-13T19:05:50.432998Z","steps":["trace[1481079733] 'process raft request' (duration: 202.784762ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:50.924732Z","caller":"traceutil/trace.go:171","msg":"trace[404058642] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"365.286006ms","start":"2026-04-13T19:05:50.559419Z","end":"2026-04-13T19:05:50.924705Z","steps":["trace[404058642] 'read index received' (duration: 365.089401ms)","trace[404058642] 'applied index is now lower than readState.Index' (duration: 195.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:50.924867Z","caller":"traceutil/trace.go:171","msg":"trace[1572964817] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"414.652023ms","start":"2026-04-13T19:05:50.510166Z","end":"2026-04-13T19:05:50.924818Z","steps":["trace[1572964817] 'process raft request' (duration: 414.291885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:50.925031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:50.510145Z","time spent":"414.811168ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:05:50.925163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.723066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m9ggk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T19:05:50.925269Z","caller":"traceutil/trace.go:171","msg":"trace[1492793116] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m9ggk; range_end:; response_count:1; response_revision:608; }","duration":"365.857059ms","start":"2026-04-13T19:05:50.55935Z","end":"2026-04-13T19:05:50.925208Z","steps":["trace[1492793116] 'agreement among raft nodes before linearized reading' (duration: 365.457329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:50.925318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:50.559322Z","time spent":"365.985632ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-m9ggk\" "} {"level":"warn","ts":"2026-04-13T19:05:51.887781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.562065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T19:05:51.887918Z","caller":"traceutil/trace.go:171","msg":"trace[918706658] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"944.191815ms","start":"2026-04-13T19:05:50.943702Z","end":"2026-04-13T19:05:51.887894Z","steps":["trace[918706658] 'process raft request' (duration: 521.399434ms)","trace[918706658] 'compare' (duration: 422.31621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:05:51.888027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:50.943683Z","time spent":"944.291608ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:05:51.925676Z","caller":"traceutil/trace.go:171","msg":"trace[1462342445] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"532.913624ms","start":"2026-04-13T19:05:51.392737Z","end":"2026-04-13T19:05:51.925651Z","steps":["trace[1462342445] 'read index received' (duration: 72.411187ms)","trace[1462342445] 'applied index is now lower than readState.Index' (duration: 460.501557ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:51.92576Z","caller":"traceutil/trace.go:171","msg":"trace[2132955711] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"594.260962ms","start":"2026-04-13T19:05:51.331462Z","end":"2026-04-13T19:05:51.925723Z","steps":["trace[2132955711] 'process raft request' (duration: 593.592926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:51.925921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.17096ms","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-04-13T19:05:51.925956Z","caller":"traceutil/trace.go:171","msg":"trace[434720519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"533.266693ms","start":"2026-04-13T19:05:51.392678Z","end":"2026-04-13T19:05:51.925944Z","steps":["trace[434720519] 'agreement among raft nodes before linearized reading' (duration: 533.117049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:51.925975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:51.331427Z","time spent":"594.414536ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:05:51.926085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.907233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T19:05:51.926114Z","caller":"traceutil/trace.go:171","msg":"trace[2056112650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"513.971935ms","start":"2026-04-13T19:05:51.412135Z","end":"2026-04-13T19:05:51.926107Z","steps":["trace[2056112650] 'agreement among raft nodes before linearized reading' (duration: 513.892253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:51.926143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:51.412115Z","time spent":"514.022465ms","remote":"127.0.0.1:51936","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-04-13T19:05:51.925996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:51.392655Z","time spent":"533.332954ms","remote":"127.0.0.1:51936","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-04-13T19:05:51.92714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.689823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T19:05:51.929291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.728727ms","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-04-13T19:05:51.92937Z","caller":"traceutil/trace.go:171","msg":"trace[1334945047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"509.28111ms","start":"2026-04-13T19:05:51.420059Z","end":"2026-04-13T19:05:51.92934Z","steps":["trace[1334945047] 'agreement among raft nodes before linearized reading' (duration: 506.105083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:51.929407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:51.420051Z","time spent":"509.346221ms","remote":"127.0.0.1:51936","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-04-13T19:05:51.929499Z","caller":"traceutil/trace.go:171","msg":"trace[1424076031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"506.916033ms","start":"2026-04-13T19:05:51.421232Z","end":"2026-04-13T19:05:51.928148Z","steps":["trace[1424076031] 'agreement among raft nodes before linearized reading' (duration: 504.876004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:51.929611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:51.421226Z","time spent":"508.348778ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T19:05:52.70146Z","caller":"traceutil/trace.go:171","msg":"trace[1576421136] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"134.023843ms","start":"2026-04-13T19:05:52.567377Z","end":"2026-04-13T19:05:52.701401Z","steps":["trace[1576421136] 'process raft request' (duration: 122.465882ms)","trace[1576421136] 'compare' (duration: 11.309135ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:52.701538Z","caller":"traceutil/trace.go:171","msg":"trace[1860135132] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"129.456982ms","start":"2026-04-13T19:05:52.572044Z","end":"2026-04-13T19:05:52.701501Z","steps":["trace[1860135132] 'read index received' (duration: 117.81757ms)","trace[1860135132] 'applied index is now lower than readState.Index' (duration: 11.635742ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:05:52.70152Z","caller":"traceutil/trace.go:171","msg":"trace[1913629144] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"118.2634ms","start":"2026-04-13T19:05:52.583224Z","end":"2026-04-13T19:05:52.701487Z","steps":["trace[1913629144] 'process raft request' (duration: 118.166868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:52.701723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.639127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:05:52.701783Z","caller":"traceutil/trace.go:171","msg":"trace[297294872] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:619; }","duration":"129.742429ms","start":"2026-04-13T19:05:52.572021Z","end":"2026-04-13T19:05:52.701763Z","steps":["trace[297294872] 'agreement among raft nodes before linearized reading' (duration: 129.608965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:54.436768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.906646ms","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-04-13T19:05:54.436869Z","caller":"traceutil/trace.go:171","msg":"trace[290861115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"489.06668ms","start":"2026-04-13T19:05:53.947779Z","end":"2026-04-13T19:05:54.436846Z","steps":["trace[290861115] 'range keys from in-memory index tree' (duration: 488.699021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:54.436915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:53.947757Z","time spent":"489.147691ms","remote":"127.0.0.1:51936","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-04-13T19:05:54.436959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.081688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T19:05:54.436958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.47234ms","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-04-13T19:05:54.437023Z","caller":"traceutil/trace.go:171","msg":"trace[1163080698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"477.17359ms","start":"2026-04-13T19:05:53.959827Z","end":"2026-04-13T19:05:54.437Z","steps":["trace[1163080698] 'range keys from in-memory index tree' (duration: 476.944855ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.437043Z","caller":"traceutil/trace.go:171","msg":"trace[1829443398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"475.596133ms","start":"2026-04-13T19:05:53.961421Z","end":"2026-04-13T19:05:54.437017Z","steps":["trace[1829443398] 'range keys from in-memory index tree' (duration: 475.282754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:54.437068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:53.959806Z","time spent":"477.251003ms","remote":"127.0.0.1:51936","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-04-13T19:05:54.437086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:53.961403Z","time spent":"475.673184ms","remote":"127.0.0.1:51936","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-04-13T19:05:54.437313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.264603ms","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-04-13T19:05:54.43737Z","caller":"traceutil/trace.go:171","msg":"trace[489768348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"477.323944ms","start":"2026-04-13T19:05:53.960033Z","end":"2026-04-13T19:05:54.437357Z","steps":["trace[489768348] 'range keys from in-memory index tree' (duration: 477.039647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:05:54.4374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:05:53.959969Z","time spent":"477.424007ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T19:05:54.555806Z","caller":"traceutil/trace.go:171","msg":"trace[463265635] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"137.751883ms","start":"2026-04-13T19:05:54.418027Z","end":"2026-04-13T19:05:54.555779Z","steps":["trace[463265635] 'process raft request' (duration: 137.60023ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.588633Z","caller":"traceutil/trace.go:171","msg":"trace[982727676] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"145.743047ms","start":"2026-04-13T19:05:54.442854Z","end":"2026-04-13T19:05:54.588597Z","steps":["trace[982727676] 'process raft request' (duration: 145.522431ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.58872Z","caller":"traceutil/trace.go:171","msg":"trace[1427031787] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"145.759047ms","start":"2026-04-13T19:05:54.44294Z","end":"2026-04-13T19:05:54.588699Z","steps":["trace[1427031787] 'process raft request' (duration: 145.557392ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.588936Z","caller":"traceutil/trace.go:171","msg":"trace[1619607295] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"145.891971ms","start":"2026-04-13T19:05:54.443024Z","end":"2026-04-13T19:05:54.588916Z","steps":["trace[1619607295] 'process raft request' (duration: 145.592943ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.58901Z","caller":"traceutil/trace.go:171","msg":"trace[1133511435] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"145.86216ms","start":"2026-04-13T19:05:54.443098Z","end":"2026-04-13T19:05:54.58896Z","steps":["trace[1133511435] 'process raft request' (duration: 145.556412ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:05:54.768589Z","caller":"traceutil/trace.go:171","msg":"trace[1148608044] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"144.230331ms","start":"2026-04-13T19:05:54.62427Z","end":"2026-04-13T19:05:54.768501Z","steps":["trace[1148608044] 'process raft request' (duration: 144.004516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:08.772138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.080093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T19:06:08.772202Z","caller":"traceutil/trace.go:171","msg":"trace[1388638861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"108.178525ms","start":"2026-04-13T19:06:08.664011Z","end":"2026-04-13T19:06:08.77219Z","steps":["trace[1388638861] 'range keys from in-memory index tree' (duration: 107.93609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:08.772235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.978143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T19:06:08.772163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.971353ms","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-04-13T19:06:08.77236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.323991ms","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-04-13T19:06:08.772308Z","caller":"traceutil/trace.go:171","msg":"trace[1694881856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"112.076266ms","start":"2026-04-13T19:06:08.66021Z","end":"2026-04-13T19:06:08.772286Z","steps":["trace[1694881856] 'range keys from in-memory index tree' (duration: 111.834159ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:08.772384Z","caller":"traceutil/trace.go:171","msg":"trace[898206869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"109.386183ms","start":"2026-04-13T19:06:08.662992Z","end":"2026-04-13T19:06:08.772378Z","steps":["trace[898206869] 'range keys from in-memory index tree' (duration: 109.199099ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:08.772419Z","caller":"traceutil/trace.go:171","msg":"trace[1101139164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"112.256819ms","start":"2026-04-13T19:06:08.660107Z","end":"2026-04-13T19:06:08.772364Z","steps":["trace[1101139164] 'range keys from in-memory index tree' (duration: 111.790389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:08.773046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.449198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m9ggk\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-04-13T19:06:08.773133Z","caller":"traceutil/trace.go:171","msg":"trace[2000709906] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m9ggk; range_end:; response_count:1; response_revision:774; }","duration":"159.570791ms","start":"2026-04-13T19:06:08.613542Z","end":"2026-04-13T19:06:08.773113Z","steps":["trace[2000709906] 'range keys from in-memory index tree' (duration: 159.265633ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:09.003577Z","caller":"traceutil/trace.go:171","msg":"trace[164320547] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"313.424879ms","start":"2026-04-13T19:06:08.690124Z","end":"2026-04-13T19:06:09.003549Z","steps":["trace[164320547] 'process raft request' (duration: 313.263605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:09.003749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:06:08.690105Z","time spent":"313.545992ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:06:09.01471Z","caller":"traceutil/trace.go:171","msg":"trace[1280249011] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"236.71424ms","start":"2026-04-13T19:06:08.777986Z","end":"2026-04-13T19:06:09.0147Z","steps":["trace[1280249011] 'process raft request' (duration: 236.479154ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:09.01515Z","caller":"traceutil/trace.go:171","msg":"trace[78304521] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:800; }","duration":"164.529364ms","start":"2026-04-13T19:06:08.85059Z","end":"2026-04-13T19:06:09.015119Z","steps":["trace[78304521] 'read index received' (duration: 152.844135ms)","trace[78304521] 'applied index is now lower than readState.Index' (duration: 11.684299ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:06:09.015478Z","caller":"traceutil/trace.go:171","msg":"trace[1859610644] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"237.472267ms","start":"2026-04-13T19:06:08.777986Z","end":"2026-04-13T19:06:09.015458Z","steps":["trace[1859610644] 'process raft request' (duration: 236.674419ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:09.015598Z","caller":"traceutil/trace.go:171","msg":"trace[285096172] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"236.711269ms","start":"2026-04-13T19:06:08.77888Z","end":"2026-04-13T19:06:09.015591Z","steps":["trace[285096172] 'process raft request' (duration: 236.045294ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:09.014588Z","caller":"traceutil/trace.go:171","msg":"trace[586419454] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"236.984436ms","start":"2026-04-13T19:06:08.777558Z","end":"2026-04-13T19:06:09.014542Z","steps":["trace[586419454] 'process raft request' (duration: 236.675309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:09.015694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.101818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:06:09.015724Z","caller":"traceutil/trace.go:171","msg":"trace[1734385503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"165.17484ms","start":"2026-04-13T19:06:08.85054Z","end":"2026-04-13T19:06:09.015715Z","steps":["trace[1734385503] 'agreement among raft nodes before linearized reading' (duration: 165.116799ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:09.015747Z","caller":"traceutil/trace.go:171","msg":"trace[618799641] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"228.579682ms","start":"2026-04-13T19:06:08.787136Z","end":"2026-04-13T19:06:09.015716Z","steps":["trace[618799641] 'process raft request' (duration: 227.879246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:09.016129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.313544ms","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-04-13T19:06:09.01621Z","caller":"traceutil/trace.go:171","msg":"trace[531665501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"136.449057ms","start":"2026-04-13T19:06:08.879744Z","end":"2026-04-13T19:06:09.016193Z","steps":["trace[531665501] 'agreement among raft nodes before linearized reading' (duration: 136.14705ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:23.337682Z","caller":"traceutil/trace.go:171","msg":"trace[405431015] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"184.316997ms","start":"2026-04-13T19:06:23.153343Z","end":"2026-04-13T19:06:23.33766Z","steps":["trace[405431015] 'process raft request' (duration: 180.387584ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:23.33781Z","caller":"traceutil/trace.go:171","msg":"trace[1326691933] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:943; }","duration":"182.284569ms","start":"2026-04-13T19:06:23.155493Z","end":"2026-04-13T19:06:23.337777Z","steps":["trace[1326691933] 'read index received' (duration: 5.408798ms)","trace[1326691933] 'applied index is now lower than readState.Index' (duration: 176.874421ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:06:23.337919Z","caller":"traceutil/trace.go:171","msg":"trace[1651641843] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"183.986459ms","start":"2026-04-13T19:06:23.153902Z","end":"2026-04-13T19:06:23.337888Z","steps":["trace[1651641843] 'process raft request' (duration: 183.676492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:23.338213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.554219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-9pmt5\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-13T19:06:23.338257Z","caller":"traceutil/trace.go:171","msg":"trace[103656866] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-9pmt5; range_end:; response_count:1; response_revision:915; }","duration":"130.62924ms","start":"2026-04-13T19:06:23.207614Z","end":"2026-04-13T19:06:23.338243Z","steps":["trace[103656866] 'agreement among raft nodes before linearized reading' (duration: 130.472557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:23.33831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.788681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-13T19:06:23.338382Z","caller":"traceutil/trace.go:171","msg":"trace[1455598240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"182.897013ms","start":"2026-04-13T19:06:23.155464Z","end":"2026-04-13T19:06:23.338361Z","steps":["trace[1455598240] 'agreement among raft nodes before linearized reading' (duration: 182.473923ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:45.685352Z","caller":"traceutil/trace.go:171","msg":"trace[736008262] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"316.911673ms","start":"2026-04-13T19:06:45.368405Z","end":"2026-04-13T19:06:45.685317Z","steps":["trace[736008262] 'process raft request' (duration: 316.717799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:45.685522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:06:45.368385Z","time spent":"317.056177ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:06:45.68854Z","caller":"traceutil/trace.go:171","msg":"trace[1108372316] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"241.911475ms","start":"2026-04-13T19:06:45.446617Z","end":"2026-04-13T19:06:45.688528Z","steps":["trace[1108372316] 'read index received' (duration: 238.525275ms)","trace[1108372316] 'applied index is now lower than readState.Index' (duration: 3.38535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:06:45.688715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.091379ms","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-04-13T19:06:45.688748Z","caller":"traceutil/trace.go:171","msg":"trace[1871217128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"242.161751ms","start":"2026-04-13T19:06:45.446579Z","end":"2026-04-13T19:06:45.688741Z","steps":["trace[1871217128] 'agreement among raft nodes before linearized reading' (duration: 242.058309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:45.68909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.456669ms","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-04-13T19:06:45.689116Z","caller":"traceutil/trace.go:171","msg":"trace[1206694994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"234.520331ms","start":"2026-04-13T19:06:45.454589Z","end":"2026-04-13T19:06:45.689109Z","steps":["trace[1206694994] 'agreement among raft nodes before linearized reading' (duration: 234.445499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:06:45.689365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.594311ms","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-04-13T19:06:45.689389Z","caller":"traceutil/trace.go:171","msg":"trace[1201984947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"242.650902ms","start":"2026-04-13T19:06:45.446731Z","end":"2026-04-13T19:06:45.689382Z","steps":["trace[1201984947] 'agreement among raft nodes before linearized reading' (duration: 242.250843ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:06:59.913026Z","caller":"traceutil/trace.go:171","msg":"trace[1474316812] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"107.489685ms","start":"2026-04-13T19:06:59.805515Z","end":"2026-04-13T19:06:59.913005Z","steps":["trace[1474316812] 'process raft request' (duration: 91.906107ms)","trace[1474316812] 'compare' (duration: 15.280361ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:06:59.913063Z","caller":"traceutil/trace.go:171","msg":"trace[1981804939] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"107.073865ms","start":"2026-04-13T19:06:59.805951Z","end":"2026-04-13T19:06:59.913025Z","steps":["trace[1981804939] 'process raft request' (duration: 106.903851ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:06.000931Z","caller":"traceutil/trace.go:171","msg":"trace[606319424] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"146.867433ms","start":"2026-04-13T19:07:05.854018Z","end":"2026-04-13T19:07:06.000885Z","steps":["trace[606319424] 'read index received' (duration: 146.620728ms)","trace[606319424] 'applied index is now lower than readState.Index' (duration: 245.175µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:06.00097Z","caller":"traceutil/trace.go:171","msg":"trace[978664038] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"147.409216ms","start":"2026-04-13T19:07:05.853518Z","end":"2026-04-13T19:07:06.000927Z","steps":["trace[978664038] 'process raft request' (duration: 147.14559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:06.001146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.108629ms","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-04-13T19:07:06.00123Z","caller":"traceutil/trace.go:171","msg":"trace[1508682102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"147.219271ms","start":"2026-04-13T19:07:05.853991Z","end":"2026-04-13T19:07:06.00121Z","steps":["trace[1508682102] 'agreement among raft nodes before linearized reading' (duration: 146.987586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:06.433251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.601179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T19:07:06.433386Z","caller":"traceutil/trace.go:171","msg":"trace[2129476473] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"325.579107ms","start":"2026-04-13T19:07:06.107786Z","end":"2026-04-13T19:07:06.433365Z","steps":["trace[2129476473] 'process raft request' (duration: 91.750953ms)","trace[2129476473] 'compare' (duration: 233.416594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:06.433456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:06.107758Z","time spent":"325.663989ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:07:06.737524Z","caller":"traceutil/trace.go:171","msg":"trace[1660733287] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"444.84506ms","start":"2026-04-13T19:07:06.292657Z","end":"2026-04-13T19:07:06.737502Z","steps":["trace[1660733287] 'process raft request' (duration: 444.712997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:06.737651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:06.292626Z","time spent":"444.955722ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:07:09.695919Z","caller":"traceutil/trace.go:171","msg":"trace[2042181091] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"131.368418ms","start":"2026-04-13T19:07:09.564525Z","end":"2026-04-13T19:07:09.695893Z","steps":["trace[2042181091] 'process raft request' (duration: 131.213644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:09.990238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.966884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:09.99031Z","caller":"traceutil/trace.go:171","msg":"trace[1771355175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"141.095107ms","start":"2026-04-13T19:07:09.849198Z","end":"2026-04-13T19:07:09.990294Z","steps":["trace[1771355175] 'range keys from in-memory index tree' (duration: 140.877102ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:10.631611Z","caller":"traceutil/trace.go:171","msg":"trace[1629178770] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"178.186552ms","start":"2026-04-13T19:07:10.453402Z","end":"2026-04-13T19:07:10.631588Z","steps":["trace[1629178770] 'process raft request' (duration: 156.938541ms)","trace[1629178770] 'compare' (duration: 20.865402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:11.200248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.034445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:11.200335Z","caller":"traceutil/trace.go:171","msg":"trace[82913400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"350.174747ms","start":"2026-04-13T19:07:10.85014Z","end":"2026-04-13T19:07:11.200315Z","steps":["trace[82913400] 'range keys from in-memory index tree' (duration: 349.944042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:11.200382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:10.850121Z","time spent":"350.25179ms","remote":"127.0.0.1:36110","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-04-13T19:07:11.20051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.823966ms","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-04-13T19:07:11.20067Z","caller":"traceutil/trace.go:171","msg":"trace[859721967] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1085; }","duration":"402.01174ms","start":"2026-04-13T19:07:10.798639Z","end":"2026-04-13T19:07:11.20065Z","steps":["trace[859721967] 'range keys from in-memory index tree' (duration: 401.803715ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:12.179001Z","caller":"traceutil/trace.go:171","msg":"trace[561239163] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"115.663357ms","start":"2026-04-13T19:07:12.063311Z","end":"2026-04-13T19:07:12.178974Z","steps":["trace[561239163] 'read index received' (duration: 115.577625ms)","trace[561239163] 'applied index is now lower than readState.Index' (duration: 84.512µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:12.179027Z","caller":"traceutil/trace.go:171","msg":"trace[278646410] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"118.268169ms","start":"2026-04-13T19:07:12.060727Z","end":"2026-04-13T19:07:12.178995Z","steps":["trace[278646410] 'process raft request' (duration: 118.088114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:12.179264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.930773ms","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-04-13T19:07:12.179462Z","caller":"traceutil/trace.go:171","msg":"trace[369327446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"116.179769ms","start":"2026-04-13T19:07:12.063264Z","end":"2026-04-13T19:07:12.179443Z","steps":["trace[369327446] 'agreement among raft nodes before linearized reading' (duration: 115.865162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:12.180051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.901043ms","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-04-13T19:07:12.180207Z","caller":"traceutil/trace.go:171","msg":"trace[8567205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"116.871656ms","start":"2026-04-13T19:07:12.063313Z","end":"2026-04-13T19:07:12.180185Z","steps":["trace[8567205] 'agreement among raft nodes before linearized reading' (duration: 115.847361ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:22.691575Z","caller":"traceutil/trace.go:171","msg":"trace[1075904464] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1123; }","duration":"113.039321ms","start":"2026-04-13T19:07:22.578511Z","end":"2026-04-13T19:07:22.69155Z","steps":["trace[1075904464] 'process raft request' (duration: 112.954199ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:22.820806Z","caller":"traceutil/trace.go:171","msg":"trace[2026951306] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"105.21277ms","start":"2026-04-13T19:07:22.715574Z","end":"2026-04-13T19:07:22.820787Z","steps":["trace[2026951306] 'process raft request' (duration: 102.241041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:25.906141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.585618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T19:07:25.906255Z","caller":"traceutil/trace.go:171","msg":"trace[1121625851] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"142.291697ms","start":"2026-04-13T19:07:25.763941Z","end":"2026-04-13T19:07:25.906233Z","steps":["trace[1121625851] 'read index received' (duration: 31.841µs)","trace[1121625851] 'applied index is now lower than readState.Index' (duration: 142.257995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:25.906586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.636834ms","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-04-13T19:07:25.906654Z","caller":"traceutil/trace.go:171","msg":"trace[741180114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"142.745797ms","start":"2026-04-13T19:07:25.763889Z","end":"2026-04-13T19:07:25.906635Z","steps":["trace[741180114] 'agreement among raft nodes before linearized reading' (duration: 142.398049ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:42.222258Z","caller":"traceutil/trace.go:171","msg":"trace[1265787621] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"183.286083ms","start":"2026-04-13T19:07:42.03895Z","end":"2026-04-13T19:07:42.222236Z","steps":["trace[1265787621] 'read index received' (duration: 183.109079ms)","trace[1265787621] 'applied index is now lower than readState.Index' (duration: 175.954µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:42.222437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.472547ms","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-04-13T19:07:42.222498Z","caller":"traceutil/trace.go:171","msg":"trace[1962178222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"183.544559ms","start":"2026-04-13T19:07:42.038941Z","end":"2026-04-13T19:07:42.222486Z","steps":["trace[1962178222] 'agreement among raft nodes before linearized reading' (duration: 183.381495ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:42.222421Z","caller":"traceutil/trace.go:171","msg":"trace[529471013] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"205.928966ms","start":"2026-04-13T19:07:42.016422Z","end":"2026-04-13T19:07:42.222351Z","steps":["trace[529471013] 'process raft request' (duration: 205.68232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:42.974011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.651897ms","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-04-13T19:07:42.974133Z","caller":"traceutil/trace.go:171","msg":"trace[862283284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"453.825431ms","start":"2026-04-13T19:07:42.520286Z","end":"2026-04-13T19:07:42.974111Z","steps":["trace[862283284] 'range keys from in-memory index tree' (duration: 453.478403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:42.974188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.52026Z","time spent":"453.915663ms","remote":"127.0.0.1:51936","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-04-13T19:07:42.974207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.538322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T19:07:42.974216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.150664ms","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-04-13T19:07:42.974271Z","caller":"traceutil/trace.go:171","msg":"trace[714417708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"488.633765ms","start":"2026-04-13T19:07:42.485622Z","end":"2026-04-13T19:07:42.974256Z","steps":["trace[714417708] 'range keys from in-memory index tree' (duration: 488.39962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:42.974206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.797274ms","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-04-13T19:07:42.974301Z","caller":"traceutil/trace.go:171","msg":"trace[1280703686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"472.264346ms","start":"2026-04-13T19:07:42.502019Z","end":"2026-04-13T19:07:42.974284Z","steps":["trace[1280703686] 'range keys from in-memory index tree' (duration: 471.98911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:42.974342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.502008Z","time spent":"472.325809ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T19:07:42.974337Z","caller":"traceutil/trace.go:171","msg":"trace[1273447768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"481.002908ms","start":"2026-04-13T19:07:42.493317Z","end":"2026-04-13T19:07:42.974319Z","steps":["trace[1273447768] 'range keys from in-memory index tree' (duration: 480.62694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:42.974444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.493281Z","time spent":"481.150672ms","remote":"127.0.0.1:51936","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-04-13T19:07:42.9743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.485603Z","time spent":"488.691017ms","remote":"127.0.0.1:51936","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-04-13T19:07:43.346787Z","caller":"traceutil/trace.go:171","msg":"trace[3012684] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"369.254457ms","start":"2026-04-13T19:07:42.977496Z","end":"2026-04-13T19:07:43.346751Z","steps":["trace[3012684] 'process raft request' (duration: 326.4958ms)","trace[3012684] 'compare' (duration: 42.564103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:43.346985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.977473Z","time spent":"369.397821ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T19:07:43.378293Z","caller":"traceutil/trace.go:171","msg":"trace[581658515] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"399.533038ms","start":"2026-04-13T19:07:42.978737Z","end":"2026-04-13T19:07:43.37827Z","steps":["trace[581658515] 'process raft request' (duration: 399.476916ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:43.378328Z","caller":"traceutil/trace.go:171","msg":"trace[369710365] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"399.67344ms","start":"2026-04-13T19:07:42.978621Z","end":"2026-04-13T19:07:43.378295Z","steps":["trace[369710365] 'process raft request' (duration: 399.495286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:43.378431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.978733Z","time spent":"399.62384ms","remote":"127.0.0.1:51936","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-04-13T19:07:43.378448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.978604Z","time spent":"399.781913ms","remote":"127.0.0.1:51936","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-04-13T19:07:43.378455Z","caller":"traceutil/trace.go:171","msg":"trace[1318038739] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"398.982694ms","start":"2026-04-13T19:07:42.97944Z","end":"2026-04-13T19:07:43.378423Z","steps":["trace[1318038739] 'process raft request' (duration: 398.80633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:43.378633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:42.979423Z","time spent":"399.128568ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:07:45.43851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.418293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:45.438601Z","caller":"traceutil/trace.go:171","msg":"trace[728038983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1263; }","duration":"880.571607ms","start":"2026-04-13T19:07:44.558012Z","end":"2026-04-13T19:07:45.438584Z","steps":["trace[728038983] 'count revisions from in-memory index tree' (duration: 880.30213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.438648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:44.557989Z","time spent":"880.644528ms","remote":"127.0.0.1:48254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T19:07:45.439412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.354551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:45.439467Z","caller":"traceutil/trace.go:171","msg":"trace[1511488805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"589.436223ms","start":"2026-04-13T19:07:44.850016Z","end":"2026-04-13T19:07:45.439452Z","steps":["trace[1511488805] 'range keys from in-memory index tree' (duration: 589.28973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.439501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:44.85Z","time spent":"589.490834ms","remote":"127.0.0.1:36110","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-04-13T19:07:45.587294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.481024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T19:07:45.587597Z","caller":"traceutil/trace.go:171","msg":"trace[1678757483] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"424.375681ms","start":"2026-04-13T19:07:45.163197Z","end":"2026-04-13T19:07:45.587572Z","steps":["trace[1678757483] 'read index received' (duration: 241.61328ms)","trace[1678757483] 'applied index is now lower than readState.Index' (duration: 182.761211ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:45.587709Z","caller":"traceutil/trace.go:171","msg":"trace[829428468] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"608.272938ms","start":"2026-04-13T19:07:44.979414Z","end":"2026-04-13T19:07:45.587687Z","steps":["trace[829428468] 'process raft request' (duration: 608.013902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.587827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:44.979397Z","time spent":"608.36109ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:07:45.587846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.619336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-13T19:07:45.587902Z","caller":"traceutil/trace.go:171","msg":"trace[29135025] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1264; }","duration":"424.721649ms","start":"2026-04-13T19:07:45.163167Z","end":"2026-04-13T19:07:45.587888Z","steps":["trace[29135025] 'agreement among raft nodes before linearized reading' (duration: 424.523324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.58789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.998274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T19:07:45.587929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:45.163147Z","time spent":"424.77703ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":133,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-13T19:07:45.587948Z","caller":"traceutil/trace.go:171","msg":"trace[1873458760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"237.120586ms","start":"2026-04-13T19:07:45.350809Z","end":"2026-04-13T19:07:45.58793Z","steps":["trace[1873458760] 'agreement among raft nodes before linearized reading' (duration: 236.921721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.588049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.104247ms","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-04-13T19:07:45.588039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.552582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:45.588111Z","caller":"traceutil/trace.go:171","msg":"trace[1539738068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"205.187099ms","start":"2026-04-13T19:07:45.382906Z","end":"2026-04-13T19:07:45.588093Z","steps":["trace[1539738068] 'agreement among raft nodes before linearized reading' (duration: 205.017165ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:45.588124Z","caller":"traceutil/trace.go:171","msg":"trace[2048234829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"145.697145ms","start":"2026-04-13T19:07:45.442404Z","end":"2026-04-13T19:07:45.588101Z","steps":["trace[2048234829] 'agreement among raft nodes before linearized reading' (duration: 145.566082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.588212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.400498ms","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-04-13T19:07:45.588354Z","caller":"traceutil/trace.go:171","msg":"trace[1317962466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"203.612713ms","start":"2026-04-13T19:07:45.384714Z","end":"2026-04-13T19:07:45.588326Z","steps":["trace[1317962466] 'agreement among raft nodes before linearized reading' (duration: 203.154042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:45.589654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.094923ms","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-04-13T19:07:45.589739Z","caller":"traceutil/trace.go:171","msg":"trace[2129523212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"207.222405ms","start":"2026-04-13T19:07:45.382503Z","end":"2026-04-13T19:07:45.589726Z","steps":["trace[2129523212] 'agreement among raft nodes before linearized reading' (duration: 205.552597ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:45.845345Z","caller":"traceutil/trace.go:171","msg":"trace[407913618] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"251.412826ms","start":"2026-04-13T19:07:45.593908Z","end":"2026-04-13T19:07:45.845321Z","steps":["trace[407913618] 'process raft request' (duration: 251.218012ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:45.845636Z","caller":"traceutil/trace.go:171","msg":"trace[871361377] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"251.622601ms","start":"2026-04-13T19:07:45.593958Z","end":"2026-04-13T19:07:45.845581Z","steps":["trace[871361377] 'process raft request' (duration: 251.322084ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:45.845726Z","caller":"traceutil/trace.go:171","msg":"trace[114085416] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"251.027157ms","start":"2026-04-13T19:07:45.594674Z","end":"2026-04-13T19:07:45.845701Z","steps":["trace[114085416] 'process raft request' (duration: 250.815322ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:46.265517Z","caller":"traceutil/trace.go:171","msg":"trace[313789175] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"413.9494ms","start":"2026-04-13T19:07:45.851541Z","end":"2026-04-13T19:07:46.265491Z","steps":["trace[313789175] 'read index received' (duration: 343.047752ms)","trace[313789175] 'applied index is now lower than readState.Index' (duration: 70.899848ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:46.265856Z","caller":"traceutil/trace.go:171","msg":"trace[400563152] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"557.922874ms","start":"2026-04-13T19:07:45.707905Z","end":"2026-04-13T19:07:46.265828Z","steps":["trace[400563152] 'process raft request' (duration: 486.647189ms)","trace[400563152] 'compare' (duration: 70.742253ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:46.265919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.362639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:46.26595Z","caller":"traceutil/trace.go:171","msg":"trace[1256510103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"414.430861ms","start":"2026-04-13T19:07:45.85151Z","end":"2026-04-13T19:07:46.265941Z","steps":["trace[1256510103] 'agreement among raft nodes before linearized reading' (duration: 414.316318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:46.265976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:45.851497Z","time spent":"414.474002ms","remote":"127.0.0.1:36110","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-04-13T19:07:46.265966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:07:45.707879Z","time spent":"558.037288ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T19:07:46.495228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.029383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T19:07:46.495306Z","caller":"traceutil/trace.go:171","msg":"trace[1060431068] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"150.097736ms","start":"2026-04-13T19:07:46.345195Z","end":"2026-04-13T19:07:46.495293Z","steps":["trace[1060431068] 'process raft request' (duration: 35.93771ms)","trace[1060431068] 'compare' (duration: 113.878829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:47.092149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.640124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:47.092222Z","caller":"traceutil/trace.go:171","msg":"trace[1566411697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"242.760067ms","start":"2026-04-13T19:07:46.849445Z","end":"2026-04-13T19:07:47.092206Z","steps":["trace[1566411697] 'range keys from in-memory index tree' (duration: 242.549561ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:47.798756Z","caller":"traceutil/trace.go:171","msg":"trace[1705186683] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"218.249861ms","start":"2026-04-13T19:07:47.580486Z","end":"2026-04-13T19:07:47.798735Z","steps":["trace[1705186683] 'process raft request' (duration: 218.141638ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:47.813993Z","caller":"traceutil/trace.go:171","msg":"trace[1693220900] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1321; }","duration":"206.650842ms","start":"2026-04-13T19:07:47.607323Z","end":"2026-04-13T19:07:47.813973Z","steps":["trace[1693220900] 'read index received' (duration: 191.313858ms)","trace[1693220900] 'applied index is now lower than readState.Index' (duration: 15.336134ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:47.81435Z","caller":"traceutil/trace.go:171","msg":"trace[1162212374] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"203.796157ms","start":"2026-04-13T19:07:47.610537Z","end":"2026-04-13T19:07:47.814333Z","steps":["trace[1162212374] 'process raft request' (duration: 203.399587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:47.81443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.088972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:07:47.814499Z","caller":"traceutil/trace.go:171","msg":"trace[1355548219] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1274; }","duration":"207.196025ms","start":"2026-04-13T19:07:47.607288Z","end":"2026-04-13T19:07:47.814484Z","steps":["trace[1355548219] 'agreement among raft nodes before linearized reading' (duration: 207.057202ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:47.8145Z","caller":"traceutil/trace.go:171","msg":"trace[1568108165] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"222.350555ms","start":"2026-04-13T19:07:47.592139Z","end":"2026-04-13T19:07:47.814489Z","steps":["trace[1568108165] 'process raft request' (duration: 221.67656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:47.828014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.528444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T19:07:47.828078Z","caller":"traceutil/trace.go:171","msg":"trace[1841359544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"201.642957ms","start":"2026-04-13T19:07:47.626419Z","end":"2026-04-13T19:07:47.828062Z","steps":["trace[1841359544] 'agreement among raft nodes before linearized reading' (duration: 201.458962ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:53.09328Z","caller":"traceutil/trace.go:171","msg":"trace[1376650746] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"129.392859ms","start":"2026-04-13T19:07:52.963863Z","end":"2026-04-13T19:07:53.093256Z","steps":["trace[1376650746] 'process raft request' (duration: 129.216085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:54.232504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.30286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T19:07:54.232653Z","caller":"traceutil/trace.go:171","msg":"trace[1491509623] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"209.074638ms","start":"2026-04-13T19:07:54.023563Z","end":"2026-04-13T19:07:54.232637Z","steps":["trace[1491509623] 'read index received' (duration: 62.559564ms)","trace[1491509623] 'applied index is now lower than readState.Index' (duration: 146.513684ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:07:54.232773Z","caller":"traceutil/trace.go:171","msg":"trace[148222414] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"216.952781ms","start":"2026-04-13T19:07:54.015797Z","end":"2026-04-13T19:07:54.232749Z","steps":["trace[148222414] 'process raft request' (duration: 70.332325ms)","trace[148222414] 'compare' (duration: 146.068403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:07:54.232995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.429727ms","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-04-13T19:07:54.233052Z","caller":"traceutil/trace.go:171","msg":"trace[854969033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"209.508709ms","start":"2026-04-13T19:07:54.023528Z","end":"2026-04-13T19:07:54.233036Z","steps":["trace[854969033] 'agreement among raft nodes before linearized reading' (duration: 209.351775ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:54.270956Z","caller":"traceutil/trace.go:171","msg":"trace[628994279] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"110.734228ms","start":"2026-04-13T19:07:54.160194Z","end":"2026-04-13T19:07:54.270928Z","steps":["trace[628994279] 'process raft request' (duration: 110.549813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:07:54.271376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.364839ms","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-04-13T19:07:54.271422Z","caller":"traceutil/trace.go:171","msg":"trace[1152027855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"246.445052ms","start":"2026-04-13T19:07:54.024967Z","end":"2026-04-13T19:07:54.271412Z","steps":["trace[1152027855] 'agreement among raft nodes before linearized reading' (duration: 246.257947ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:54.421114Z","caller":"traceutil/trace.go:171","msg":"trace[1777198641] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"122.858997ms","start":"2026-04-13T19:07:54.298222Z","end":"2026-04-13T19:07:54.421081Z","steps":["trace[1777198641] 'process raft request' (duration: 122.666003ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:07:54.421292Z","caller":"traceutil/trace.go:171","msg":"trace[1944940247] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"121.781563ms","start":"2026-04-13T19:07:54.299494Z","end":"2026-04-13T19:07:54.421275Z","steps":["trace[1944940247] 'process raft request' (duration: 121.518117ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:08:10.559428Z","caller":"traceutil/trace.go:171","msg":"trace[228076642] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"160.971317ms","start":"2026-04-13T19:08:10.398432Z","end":"2026-04-13T19:08:10.559403Z","steps":["trace[228076642] 'read index received' (duration: 114.253579ms)","trace[228076642] 'applied index is now lower than readState.Index' (duration: 46.716818ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:08:10.559528Z","caller":"traceutil/trace.go:171","msg":"trace[849295321] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"163.412574ms","start":"2026-04-13T19:08:10.396081Z","end":"2026-04-13T19:08:10.559494Z","steps":["trace[849295321] 'process raft request' (duration: 116.668434ms)","trace[849295321] 'compare' (duration: 46.544425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:08:10.559606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.158002ms","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-04-13T19:08:10.559639Z","caller":"traceutil/trace.go:171","msg":"trace[1875532204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1409; }","duration":"161.231634ms","start":"2026-04-13T19:08:10.398397Z","end":"2026-04-13T19:08:10.559629Z","steps":["trace[1875532204] 'agreement among raft nodes before linearized reading' (duration: 161.099121ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:08:12.730461Z","caller":"traceutil/trace.go:171","msg":"trace[593880557] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"133.538334ms","start":"2026-04-13T19:08:12.596895Z","end":"2026-04-13T19:08:12.730433Z","steps":["trace[593880557] 'process raft request' (duration: 105.755932ms)","trace[593880557] 'compare' (duration: 27.403323ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T19:08:14.187434Z","caller":"traceutil/trace.go:171","msg":"trace[578612560] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"214.043503ms","start":"2026-04-13T19:08:13.973361Z","end":"2026-04-13T19:08:14.187404Z","steps":["trace[578612560] 'process raft request' (duration: 213.845518ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:08:24.741245Z","caller":"traceutil/trace.go:171","msg":"trace[952387776] linearizableReadLoop","detail":"{readStateIndex:1526; appliedIndex:1525; }","duration":"448.953013ms","start":"2026-04-13T19:08:24.292257Z","end":"2026-04-13T19:08:24.74121Z","steps":["trace[952387776] 'read index received' (duration: 448.790449ms)","trace[952387776] 'applied index is now lower than readState.Index' (duration: 161.354µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:08:24.74147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.187749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T19:08:24.741511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.899023ms","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-04-13T19:08:24.74154Z","caller":"traceutil/trace.go:171","msg":"trace[1792365692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1469; }","duration":"449.313771ms","start":"2026-04-13T19:08:24.292212Z","end":"2026-04-13T19:08:24.741526Z","steps":["trace[1792365692] 'agreement among raft nodes before linearized reading' (duration: 449.095716ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:08:24.741552Z","caller":"traceutil/trace.go:171","msg":"trace[1468309648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1469; }","duration":"217.955044ms","start":"2026-04-13T19:08:24.523585Z","end":"2026-04-13T19:08:24.74154Z","steps":["trace[1468309648] 'agreement among raft nodes before linearized reading' (duration: 217.808181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:08:24.741587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:08:24.292193Z","time spent":"449.385593ms","remote":"127.0.0.1:51936","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-04-13T19:08:24.741702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.376145ms","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-04-13T19:08:24.741735Z","caller":"traceutil/trace.go:171","msg":"trace[1339944792] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1469; }","duration":"116.448377ms","start":"2026-04-13T19:08:24.625276Z","end":"2026-04-13T19:08:24.741724Z","steps":["trace[1339944792] 'agreement among raft nodes before linearized reading' (duration: 116.373315ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:08:24.741744Z","caller":"traceutil/trace.go:171","msg":"trace[1533386769] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"524.921603ms","start":"2026-04-13T19:08:24.216335Z","end":"2026-04-13T19:08:24.741257Z","steps":["trace[1533386769] 'process raft request' (duration: 524.720288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:08:24.742208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:08:24.216312Z","time spent":"525.521826ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T19:09:09.664286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.478056ms","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-04-13T19:09:09.664446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.078363ms","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-04-13T19:09:09.66461Z","caller":"traceutil/trace.go:171","msg":"trace[1554119354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1673; }","duration":"367.800853ms","start":"2026-04-13T19:09:09.296712Z","end":"2026-04-13T19:09:09.664513Z","steps":["trace[1554119354] 'range keys from in-memory index tree' (duration: 367.24414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:09:09.664739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:09:09.296689Z","time spent":"368.006788ms","remote":"127.0.0.1:51936","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-04-13T19:09:09.66465Z","caller":"traceutil/trace.go:171","msg":"trace[581702091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1673; }","duration":"325.326298ms","start":"2026-04-13T19:09:09.339302Z","end":"2026-04-13T19:09:09.664628Z","steps":["trace[581702091] 'range keys from in-memory index tree' (duration: 324.903598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:09:09.664846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:09:09.339285Z","time spent":"325.548434ms","remote":"127.0.0.1:51936","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-04-13T19:09:09.664991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.072608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-13T19:09:09.665026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.90402ms","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-04-13T19:09:09.665131Z","caller":"traceutil/trace.go:171","msg":"trace[1554779306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1673; }","duration":"467.250222ms","start":"2026-04-13T19:09:09.197856Z","end":"2026-04-13T19:09:09.665106Z","steps":["trace[1554779306] 'range keys from in-memory index tree' (duration: 466.938695ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:09:09.665135Z","caller":"traceutil/trace.go:171","msg":"trace[163145717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1673; }","duration":"495.050573ms","start":"2026-04-13T19:09:09.170065Z","end":"2026-04-13T19:09:09.665116Z","steps":["trace[163145717] 'range keys from in-memory index tree' (duration: 494.697505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:09:09.665187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:09:09.170045Z","time spent":"495.129025ms","remote":"127.0.0.1:51936","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-04-13T19:09:09.665185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T19:09:09.197844Z","time spent":"467.326084ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T19:10:02.128839Z","caller":"traceutil/trace.go:171","msg":"trace[500657643] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"144.700656ms","start":"2026-04-13T19:10:01.984109Z","end":"2026-04-13T19:10:02.12881Z","steps":["trace[500657643] 'process raft request' (duration: 144.40735ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:10:09.560074Z","caller":"traceutil/trace.go:171","msg":"trace[2031712505] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"108.287339ms","start":"2026-04-13T19:10:09.451761Z","end":"2026-04-13T19:10:09.560048Z","steps":["trace[2031712505] 'process raft request' (duration: 108.128295ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:10:10.284339Z","caller":"traceutil/trace.go:171","msg":"trace[1215861668] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"101.336118ms","start":"2026-04-13T19:10:10.182933Z","end":"2026-04-13T19:10:10.284269Z","steps":["trace[1215861668] 'process raft request' (duration: 34.101594ms)","trace[1215861668] 'compare' (duration: 67.06131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T19:11:28.995573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.119643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"warn","ts":"2026-04-13T19:11:28.995601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.912925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T19:11:28.995676Z","caller":"traceutil/trace.go:171","msg":"trace[649421656] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:2350; }","duration":"125.271126ms","start":"2026-04-13T19:11:28.870383Z","end":"2026-04-13T19:11:28.995654Z","steps":["trace[649421656] 'range keys from in-memory index tree' (duration: 125.016891ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T19:11:28.995695Z","caller":"traceutil/trace.go:171","msg":"trace[1987891735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2350; }","duration":"146.057669ms","start":"2026-04-13T19:11:28.849617Z","end":"2026-04-13T19:11:28.995674Z","steps":["trace[1987891735] 'range keys from in-memory index tree' (duration: 145.831023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T19:11:28.995787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.400506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-04-13T19:11:28.995872Z","caller":"traceutil/trace.go:171","msg":"trace[1151111139] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:2350; }","duration":"124.519449ms","start":"2026-04-13T19:11:28.871328Z","end":"2026-04-13T19:11:28.995847Z","steps":["trace[1151111139] 'range keys from in-memory index tree' (duration: 124.318325ms)"],"step_count":1}