{"level":"warn","ts":"2026-07-07T11:21:21.748031Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T11:21:21.748171Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-07T11:21:21.748271Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T11:21:21.748292Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-07T11:21:21.748321Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-07T11:21:21.749313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-07-07T11:21:21.749424Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-07T11:21:21.75343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.708151ms"} {"level":"info","ts":"2026-07-07T11:21:21.757605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-07-07T11:21:21.757708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T11:21:21.75774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-07-07T11:21:21.757757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T11:21:21.757776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-07-07T11:21:21.757827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-07-07T11:21:21.766288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T11:21:21.768133Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T11:21:21.769237Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-07T11:21:21.771287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T11:21:21.774078Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T11:21:21.774426Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T11:21:21.774486Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T11:21:21.774568Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T11:21:21.77544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-07-07T11:21:21.776752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-07T11:21:21.777418Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-07T11:21:21.777638Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-07T11:21:21.777683Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-07T11:21:21.777708Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T11:21:21.777752Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T11:21:22.558443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T11:21:22.558504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T11:21:22.558569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-07-07T11:21:22.558589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-07-07T11:21:22.558601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-07T11:21:22.558626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-07-07T11:21:22.55864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-07T11:21:22.560752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T11:21:22.560794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T11:21:22.560811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T11:21:22.560838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T11:21:22.561215Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T11:21:22.561322Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T11:21:22.56194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T11:21:22.562205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T11:21:22.56235Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T11:21:22.563984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-07-07T11:21:22.565147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-07T11:21:28.833763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119561306857407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T11:21:29.126303Z","caller":"traceutil/trace.go:171","msg":"trace[1771240846] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"815.35342ms","start":"2026-07-07T11:21:28.310926Z","end":"2026-07-07T11:21:29.12628Z","steps":["trace[1771240846] 'process raft request' (duration: 815.203276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.126849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.310913Z","time spent":"815.474263ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:21:29.733418Z","caller":"traceutil/trace.go:171","msg":"trace[1867041420] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"1.231895825s","start":"2026-07-07T11:21:28.501497Z","end":"2026-07-07T11:21:29.733393Z","steps":["trace[1867041420] 'process raft request' (duration: 1.231730552s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.733584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.501489Z","time spent":"1.232035077s","remote":"127.0.0.1:54400","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-07-07T11:21:29.733754Z","caller":"traceutil/trace.go:171","msg":"trace[1682335131] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"1.231713191s","start":"2026-07-07T11:21:28.501976Z","end":"2026-07-07T11:21:29.733689Z","steps":["trace[1682335131] 'process raft request' (duration: 1.231342544s)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:29.733974Z","caller":"traceutil/trace.go:171","msg":"trace[1802986438] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:318; }","duration":"1.400425564s","start":"2026-07-07T11:21:28.333528Z","end":"2026-07-07T11:21:29.733954Z","steps":["trace[1802986438] 'read index received' (duration: 792.653943ms)","trace[1802986438] 'applied index is now lower than readState.Index' (duration: 607.770491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:21:29.733979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.50197Z","time spent":"1.231913505s","remote":"127.0.0.1:54400","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-07-07T11:21:29.734601Z","caller":"traceutil/trace.go:171","msg":"trace[1380336092] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"1.231271733s","start":"2026-07-07T11:21:28.502784Z","end":"2026-07-07T11:21:29.734055Z","steps":["trace[1380336092] 'process raft request' (duration: 1.231137711s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.734913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.502779Z","time spent":"1.232095959s","remote":"127.0.0.1:54400","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-07-07T11:21:29.735055Z","caller":"traceutil/trace.go:171","msg":"trace[2070973121] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"1.232988685s","start":"2026-07-07T11:21:28.502039Z","end":"2026-07-07T11:21:29.735027Z","steps":["trace[2070973121] 'process raft request' (duration: 1.231304833s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.73511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.502033Z","time spent":"1.233051957s","remote":"127.0.0.1:54400","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-07-07T11:21:29.736368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402788209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T11:21:29.736527Z","caller":"traceutil/trace.go:171","msg":"trace[438540303] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:312; }","duration":"1.402985163s","start":"2026-07-07T11:21:28.333522Z","end":"2026-07-07T11:21:29.736507Z","steps":["trace[438540303] 'agreement among raft nodes before linearized reading' (duration: 1.400539496s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.736648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.333479Z","time spent":"1.403154086s","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-07T11:21:29.903849Z","caller":"traceutil/trace.go:171","msg":"trace[262231080] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"169.810233ms","start":"2026-07-07T11:21:29.734015Z","end":"2026-07-07T11:21:29.903825Z","steps":["trace[262231080] 'read index received' (duration: 169.64241ms)","trace[262231080] 'applied index is now lower than readState.Index' (duration: 166.783µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:21:29.903918Z","caller":"traceutil/trace.go:171","msg":"trace[1796246036] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"772.622949ms","start":"2026-07-07T11:21:29.131259Z","end":"2026-07-07T11:21:29.903882Z","steps":["trace[1796246036] 'process raft request' (duration: 772.346144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.904637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:29.131246Z","time spent":"773.323471ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T11:21:29.903998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.70169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:21:29.905255Z","caller":"traceutil/trace.go:171","msg":"trace[1525131463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:313; }","duration":"775.001065ms","start":"2026-07-07T11:21:29.130232Z","end":"2026-07-07T11:21:29.905234Z","steps":["trace[1525131463] 'agreement among raft nodes before linearized reading' (duration: 773.612908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.905455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:29.130213Z","time spent":"775.212188ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T11:21:29.904076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483111182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-07T11:21:29.904143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402635906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-07T11:21:29.905839Z","caller":"traceutil/trace.go:171","msg":"trace[216427876] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"1.404422811s","start":"2026-07-07T11:21:28.501402Z","end":"2026-07-07T11:21:29.905825Z","steps":["trace[216427876] 'agreement among raft nodes before linearized reading' (duration: 1.402615666s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.905996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.501388Z","time spent":"1.404568653s","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-07-07T11:21:29.906061Z","caller":"traceutil/trace.go:171","msg":"trace[1828103645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"1.485120921s","start":"2026-07-07T11:21:28.420917Z","end":"2026-07-07T11:21:29.906038Z","steps":["trace[1828103645] 'agreement among raft nodes before linearized reading' (duration: 1.483130433s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.906409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:28.420886Z","time spent":"1.485495419s","remote":"127.0.0.1:54466","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-07-07T11:21:29.974787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.312671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"warn","ts":"2026-07-07T11:21:29.974981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.729219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-07-07T11:21:29.975047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.606707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-07-07T11:21:29.975082Z","caller":"traceutil/trace.go:171","msg":"trace[1444588246] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:314; }","duration":"238.884981ms","start":"2026-07-07T11:21:29.736178Z","end":"2026-07-07T11:21:29.975063Z","steps":["trace[1444588246] 'agreement among raft nodes before linearized reading' (duration: 238.405311ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:29.975092Z","caller":"traceutil/trace.go:171","msg":"trace[474690107] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:314; }","duration":"237.694958ms","start":"2026-07-07T11:21:29.737385Z","end":"2026-07-07T11:21:29.97508Z","steps":["trace[474690107] 'agreement among raft nodes before linearized reading' (duration: 237.379002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:29.974854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.695038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-07T11:21:29.974879Z","caller":"traceutil/trace.go:171","msg":"trace[731152391] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:314; }","duration":"237.537345ms","start":"2026-07-07T11:21:29.737325Z","end":"2026-07-07T11:21:29.974863Z","steps":["trace[731152391] 'agreement among raft nodes before linearized reading' (duration: 237.222918ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:29.975331Z","caller":"traceutil/trace.go:171","msg":"trace[916724094] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:314; }","duration":"238.166917ms","start":"2026-07-07T11:21:29.737119Z","end":"2026-07-07T11:21:29.975286Z","steps":["trace[916724094] 'agreement among raft nodes before linearized reading' (duration: 237.529764ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:29.97543Z","caller":"traceutil/trace.go:171","msg":"trace[1290480746] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"232.336085ms","start":"2026-07-07T11:21:29.742346Z","end":"2026-07-07T11:21:29.974682Z","steps":["trace[1290480746] 'process raft request' (duration: 173.083756ms)","trace[1290480746] 'compare' (duration: 58.869581ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:21:57.805223Z","caller":"traceutil/trace.go:171","msg":"trace[1702226295] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"146.011166ms","start":"2026-07-07T11:21:57.659185Z","end":"2026-07-07T11:21:57.805196Z","steps":["trace[1702226295] 'process raft request' (duration: 145.810201ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.805267Z","caller":"traceutil/trace.go:171","msg":"trace[424447986] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"145.984536ms","start":"2026-07-07T11:21:57.659251Z","end":"2026-07-07T11:21:57.805236Z","steps":["trace[424447986] 'process raft request' (duration: 145.853492ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.806024Z","caller":"traceutil/trace.go:171","msg":"trace[1668676984] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"116.284733ms","start":"2026-07-07T11:21:57.689718Z","end":"2026-07-07T11:21:57.806003Z","steps":["trace[1668676984] 'process raft request' (duration: 115.779343ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.806031Z","caller":"traceutil/trace.go:171","msg":"trace[1525880424] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"146.32614ms","start":"2026-07-07T11:21:57.659664Z","end":"2026-07-07T11:21:57.80599Z","steps":["trace[1525880424] 'process raft request' (duration: 145.481905ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.993369Z","caller":"traceutil/trace.go:171","msg":"trace[1775446194] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"239.934763ms","start":"2026-07-07T11:21:57.753409Z","end":"2026-07-07T11:21:57.993344Z","steps":["trace[1775446194] 'process raft request' (duration: 239.80432ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.993419Z","caller":"traceutil/trace.go:171","msg":"trace[2075112341] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"240.479114ms","start":"2026-07-07T11:21:57.752815Z","end":"2026-07-07T11:21:57.993294Z","steps":["trace[2075112341] 'process raft request' (duration: 153.433246ms)","trace[2075112341] 'compare' (duration: 86.774543ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:21:57.993677Z","caller":"traceutil/trace.go:171","msg":"trace[1811783315] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"233.42262ms","start":"2026-07-07T11:21:57.76022Z","end":"2026-07-07T11:21:57.993642Z","steps":["trace[1811783315] 'process raft request' (duration: 233.065994ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.995671Z","caller":"traceutil/trace.go:171","msg":"trace[851170775] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:524; }","duration":"183.065817ms","start":"2026-07-07T11:21:57.812589Z","end":"2026-07-07T11:21:57.995655Z","steps":["trace[851170775] 'read index received' (duration: 93.675134ms)","trace[851170775] 'applied index is now lower than readState.Index' (duration: 89.389583ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:21:57.996026Z","caller":"traceutil/trace.go:171","msg":"trace[1505965574] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"186.160925ms","start":"2026-07-07T11:21:57.809854Z","end":"2026-07-07T11:21:57.996015Z","steps":["trace[1505965574] 'process raft request' (duration: 185.645585ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.996407Z","caller":"traceutil/trace.go:171","msg":"trace[17714843] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"184.321981ms","start":"2026-07-07T11:21:57.812058Z","end":"2026-07-07T11:21:57.99638Z","steps":["trace[17714843] 'process raft request' (duration: 183.558287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:57.996497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.902541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-07-07T11:21:57.996568Z","caller":"traceutil/trace.go:171","msg":"trace[1694949010] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"186.47347ms","start":"2026-07-07T11:21:57.810081Z","end":"2026-07-07T11:21:57.996555Z","steps":["trace[1694949010] 'process raft request' (duration: 185.496482ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:57.996585Z","caller":"traceutil/trace.go:171","msg":"trace[1338009848] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:513; }","duration":"184.029594ms","start":"2026-07-07T11:21:57.812535Z","end":"2026-07-07T11:21:57.996564Z","steps":["trace[1338009848] 'agreement among raft nodes before linearized reading' (duration: 183.77093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:21:57.996883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.090937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-07-07T11:21:57.998295Z","caller":"traceutil/trace.go:171","msg":"trace[1269654552] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:513; }","duration":"117.525994ms","start":"2026-07-07T11:21:57.88073Z","end":"2026-07-07T11:21:57.998256Z","steps":["trace[1269654552] 'agreement among raft nodes before linearized reading' (duration: 115.568287ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:59.804599Z","caller":"traceutil/trace.go:171","msg":"trace[617850589] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"107.149979ms","start":"2026-07-07T11:21:59.697371Z","end":"2026-07-07T11:21:59.804521Z","steps":["trace[617850589] 'process raft request' (duration: 106.956055ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:59.936424Z","caller":"traceutil/trace.go:171","msg":"trace[329309990] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"238.282413ms","start":"2026-07-07T11:21:59.698094Z","end":"2026-07-07T11:21:59.936377Z","steps":["trace[329309990] 'process raft request' (duration: 191.939355ms)","trace[329309990] 'compare' (duration: 46.045981ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:21:59.936525Z","caller":"traceutil/trace.go:171","msg":"trace[786227699] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"237.329285ms","start":"2026-07-07T11:21:59.699156Z","end":"2026-07-07T11:21:59.936485Z","steps":["trace[786227699] 'process raft request' (duration: 237.139511ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:21:59.936813Z","caller":"traceutil/trace.go:171","msg":"trace[1191240898] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"225.479709ms","start":"2026-07-07T11:21:59.711298Z","end":"2026-07-07T11:21:59.936778Z","steps":["trace[1191240898] 'process raft request' (duration: 225.118492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.507076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119561306857925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T11:22:00.833319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.962938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:150495"} {"level":"info","ts":"2026-07-07T11:22:00.833459Z","caller":"traceutil/trace.go:171","msg":"trace[189866749] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"865.723351ms","start":"2026-07-07T11:21:59.966938Z","end":"2026-07-07T11:22:00.832662Z","steps":["trace[189866749] 'process raft request' (duration: 782.561266ms)","trace[189866749] 'compare' (duration: 82.295959ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:00.833622Z","caller":"traceutil/trace.go:171","msg":"trace[492256921] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"826.5995ms","start":"2026-07-07T11:22:00.006331Z","end":"2026-07-07T11:22:00.83293Z","steps":["trace[492256921] 'read index received' (duration: 743.289013ms)","trace[492256921] 'applied index is now lower than readState.Index' (duration: 83.308387ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:00.833719Z","caller":"traceutil/trace.go:171","msg":"trace[1619782356] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"812.447813ms","start":"2026-07-07T11:22:00.020594Z","end":"2026-07-07T11:22:00.833042Z","steps":["trace[1619782356] 'process raft request' (duration: 811.934614ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:00.833831Z","caller":"traceutil/trace.go:171","msg":"trace[2000530693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:578; }","duration":"827.1099ms","start":"2026-07-07T11:22:00.006279Z","end":"2026-07-07T11:22:00.833389Z","steps":["trace[2000530693] 'agreement among raft nodes before linearized reading' (duration: 826.654361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.833941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.006257Z","time spent":"827.644431ms","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":150518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-07T11:22:00.833825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.020568Z","time spent":"813.194237ms","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T11:22:00.83451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:21:59.966891Z","time spent":"866.70991ms","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T11:22:00.857802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.617213ms","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-07-07T11:22:00.857876Z","caller":"traceutil/trace.go:171","msg":"trace[1144362200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"741.723034ms","start":"2026-07-07T11:22:00.116125Z","end":"2026-07-07T11:22:00.857848Z","steps":["trace[1144362200] 'agreement among raft nodes before linearized reading' (duration: 741.609612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.857914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.116088Z","time spent":"741.809736ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T11:22:00.858282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.656851ms","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-07-07T11:22:00.858362Z","caller":"traceutil/trace.go:171","msg":"trace[1324308027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"743.790683ms","start":"2026-07-07T11:22:00.114553Z","end":"2026-07-07T11:22:00.858344Z","steps":["trace[1324308027] 'agreement among raft nodes before linearized reading' (duration: 743.651031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.858397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.114533Z","time spent":"743.857764ms","remote":"127.0.0.1:54466","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-07-07T11:22:00.859877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.009724ms","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-07-07T11:22:00.859949Z","caller":"traceutil/trace.go:171","msg":"trace[2024184114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"747.149556ms","start":"2026-07-07T11:22:00.112785Z","end":"2026-07-07T11:22:00.859935Z","steps":["trace[2024184114] 'agreement among raft nodes before linearized reading' (duration: 744.551377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.860058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.112762Z","time spent":"747.286348ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T11:22:00.857273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.26439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:22:00.860451Z","caller":"traceutil/trace.go:171","msg":"trace[1927374419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"486.488221ms","start":"2026-07-07T11:22:00.373947Z","end":"2026-07-07T11:22:00.860435Z","steps":["trace[1927374419] 'agreement among raft nodes before linearized reading' (duration: 483.247829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:00.860681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:00.373928Z","time spent":"486.621585ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T11:22:02.725393Z","caller":"traceutil/trace.go:171","msg":"trace[137614881] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"122.437179ms","start":"2026-07-07T11:22:02.602916Z","end":"2026-07-07T11:22:02.725353Z","steps":["trace[137614881] 'process raft request' (duration: 122.201674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:03.964018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.276079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:03.9641Z","caller":"traceutil/trace.go:171","msg":"trace[1701779108] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:640; }","duration":"710.416061ms","start":"2026-07-07T11:22:03.253663Z","end":"2026-07-07T11:22:03.964079Z","steps":["trace[1701779108] 'range keys from in-memory index tree' (duration: 710.187977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:03.964141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:03.253637Z","time spent":"710.494872ms","remote":"127.0.0.1:54416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-07-07T11:22:03.966531Z","caller":"traceutil/trace.go:171","msg":"trace[2053919928] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"610.646502ms","start":"2026-07-07T11:22:03.355847Z","end":"2026-07-07T11:22:03.966494Z","steps":["trace[2053919928] 'process raft request' (duration: 610.474399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:03.966769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:03.355801Z","time spent":"610.838646ms","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:22:03.971608Z","caller":"traceutil/trace.go:171","msg":"trace[343848080] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"412.159314ms","start":"2026-07-07T11:22:03.559389Z","end":"2026-07-07T11:22:03.971548Z","steps":["trace[343848080] 'process raft request' (duration: 411.998101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:03.971884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:03.559343Z","time spent":"412.317657ms","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:22:03.971979Z","caller":"traceutil/trace.go:171","msg":"trace[1705654260] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"213.096505ms","start":"2026-07-07T11:22:03.758864Z","end":"2026-07-07T11:22:03.971961Z","steps":["trace[1705654260] 'process raft request' (duration: 212.624546ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:11.156394Z","caller":"traceutil/trace.go:171","msg":"trace[1684894889] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"527.532809ms","start":"2026-07-07T11:22:10.628837Z","end":"2026-07-07T11:22:11.15637Z","steps":["trace[1684894889] 'process raft request' (duration: 527.358716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:11.156552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:10.628812Z","time spent":"527.670451ms","remote":"127.0.0.1:54400","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-07-07T11:22:11.203012Z","caller":"traceutil/trace.go:171","msg":"trace[1750347134] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"285.335712ms","start":"2026-07-07T11:22:10.917614Z","end":"2026-07-07T11:22:11.202949Z","steps":["trace[1750347134] 'read index received' (duration: 238.558007ms)","trace[1750347134] 'applied index is now lower than readState.Index' (duration: 46.776115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:22:11.203268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.787074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-07T11:22:11.20328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.645948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:22:11.203339Z","caller":"traceutil/trace.go:171","msg":"trace[707396271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"273.914587ms","start":"2026-07-07T11:22:10.929405Z","end":"2026-07-07T11:22:11.203319Z","steps":["trace[707396271] 'agreement among raft nodes before linearized reading' (duration: 273.727853ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:11.203352Z","caller":"traceutil/trace.go:171","msg":"trace[2112298385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"285.767571ms","start":"2026-07-07T11:22:10.917567Z","end":"2026-07-07T11:22:11.203335Z","steps":["trace[2112298385] 'agreement among raft nodes before linearized reading' (duration: 285.500686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:11.20352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.47129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:22:11.203557Z","caller":"traceutil/trace.go:171","msg":"trace[1607994309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"271.572393ms","start":"2026-07-07T11:22:10.931975Z","end":"2026-07-07T11:22:11.203547Z","steps":["trace[1607994309] 'agreement among raft nodes before linearized reading' (duration: 271.481001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:11.203631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.294727ms","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-07-07T11:22:11.203823Z","caller":"traceutil/trace.go:171","msg":"trace[324778754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"271.506532ms","start":"2026-07-07T11:22:10.932248Z","end":"2026-07-07T11:22:11.203754Z","steps":["trace[324778754] 'agreement among raft nodes before linearized reading' (duration: 270.987962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:12.730593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.091123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-07T11:22:12.730668Z","caller":"traceutil/trace.go:171","msg":"trace[1655298086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:706; }","duration":"469.224326ms","start":"2026-07-07T11:22:12.261424Z","end":"2026-07-07T11:22:12.730649Z","steps":["trace[1655298086] 'range keys from in-memory index tree' (duration: 468.906358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:12.730666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.113844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:12.730707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:12.261398Z","time spent":"469.301037ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-07T11:22:12.730742Z","caller":"traceutil/trace.go:171","msg":"trace[1333372033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"778.246107ms","start":"2026-07-07T11:22:11.952475Z","end":"2026-07-07T11:22:12.730721Z","steps":["trace[1333372033] 'range keys from in-memory index tree' (duration: 777.90131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:12.730801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:11.952451Z","time spent":"778.342008ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T11:22:24.125819Z","caller":"traceutil/trace.go:171","msg":"trace[659747632] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"165.647826ms","start":"2026-07-07T11:22:23.960147Z","end":"2026-07-07T11:22:24.125794Z","steps":["trace[659747632] 'process raft request' (duration: 109.349ms)","trace[659747632] 'compare' (duration: 56.101382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:22:27.750229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.954544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T11:22:27.750675Z","caller":"traceutil/trace.go:171","msg":"trace[1283992701] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"358.408397ms","start":"2026-07-07T11:22:27.392235Z","end":"2026-07-07T11:22:27.750644Z","steps":["trace[1283992701] 'process raft request' (duration: 81.78923ms)","trace[1283992701] 'compare' (duration: 275.742011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:22:27.750788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:27.392209Z","time spent":"358.520498ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:22:27.750792Z","caller":"traceutil/trace.go:171","msg":"trace[2044617985] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:839; }","duration":"354.255128ms","start":"2026-07-07T11:22:27.39652Z","end":"2026-07-07T11:22:27.750776Z","steps":["trace[2044617985] 'read index received' (duration: 77.514719ms)","trace[2044617985] 'applied index is now lower than readState.Index' (duration: 276.739249ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:27.750877Z","caller":"traceutil/trace.go:171","msg":"trace[783144381] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"358.215383ms","start":"2026-07-07T11:22:27.392621Z","end":"2026-07-07T11:22:27.750837Z","steps":["trace[783144381] 'process raft request' (duration: 357.720514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:27.750953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.432771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:22:27.750996Z","caller":"traceutil/trace.go:171","msg":"trace[1011155202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"354.498772ms","start":"2026-07-07T11:22:27.396487Z","end":"2026-07-07T11:22:27.750986Z","steps":["trace[1011155202] 'agreement among raft nodes before linearized reading' (duration: 354.340359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:27.751025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:27.396474Z","time spent":"354.542522ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T11:22:27.751036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:27.392603Z","time spent":"358.335125ms","remote":"127.0.0.1:54466","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-07-07T11:22:31.548873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.214604ms","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-07-07T11:22:31.548948Z","caller":"traceutil/trace.go:171","msg":"trace[349554114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:829; }","duration":"723.329486ms","start":"2026-07-07T11:22:30.825602Z","end":"2026-07-07T11:22:31.548931Z","steps":["trace[349554114] 'range keys from in-memory index tree' (duration: 723.064082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.548991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:30.825583Z","time spent":"723.397158ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T11:22:31.54906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.289143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xd8h9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-07T11:22:31.549134Z","caller":"traceutil/trace.go:171","msg":"trace[468382751] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xd8h9; range_end:; response_count:1; response_revision:829; }","duration":"819.410685ms","start":"2026-07-07T11:22:30.729706Z","end":"2026-07-07T11:22:31.549117Z","steps":["trace[468382751] 'range keys from in-memory index tree' (duration: 819.069509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.549194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:30.729684Z","time spent":"819.499337ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xd8h9\" "} {"level":"warn","ts":"2026-07-07T11:22:31.693965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.46379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T11:22:31.694485Z","caller":"traceutil/trace.go:171","msg":"trace[1910416253] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"743.07636ms","start":"2026-07-07T11:22:30.951344Z","end":"2026-07-07T11:22:31.69442Z","steps":["trace[1910416253] 'read index received' (duration: 58.188932ms)","trace[1910416253] 'applied index is now lower than readState.Index' (duration: 684.885688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:22:31.694607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.254383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.694681Z","caller":"traceutil/trace.go:171","msg":"trace[982909496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"743.377525ms","start":"2026-07-07T11:22:30.951294Z","end":"2026-07-07T11:22:31.694671Z","steps":["trace[982909496] 'agreement among raft nodes before linearized reading' (duration: 743.209002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.694719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:30.951267Z","time spent":"743.446267ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T11:22:31.694853Z","caller":"traceutil/trace.go:171","msg":"trace[616486941] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"141.416977ms","start":"2026-07-07T11:22:31.553423Z","end":"2026-07-07T11:22:31.69484Z","steps":["trace[616486941] 'process raft request' (duration: 140.937029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.699478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.912348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.699528Z","caller":"traceutil/trace.go:171","msg":"trace[1737582672] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"142.204401ms","start":"2026-07-07T11:22:31.557304Z","end":"2026-07-07T11:22:31.699509Z","steps":["trace[1737582672] 'process raft request' (duration: 141.963637ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:31.699568Z","caller":"traceutil/trace.go:171","msg":"trace[621026233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"159.057942ms","start":"2026-07-07T11:22:31.540487Z","end":"2026-07-07T11:22:31.699545Z","steps":["trace[621026233] 'agreement among raft nodes before linearized reading' (duration: 158.897188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.699582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.7188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:22:31.699617Z","caller":"traceutil/trace.go:171","msg":"trace[152981894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"276.786561ms","start":"2026-07-07T11:22:31.422821Z","end":"2026-07-07T11:22:31.699607Z","steps":["trace[152981894] 'agreement among raft nodes before linearized reading' (duration: 276.678728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.699668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.924249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:31.699752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.505281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.699785Z","caller":"traceutil/trace.go:171","msg":"trace[1492965355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:831; }","duration":"263.581751ms","start":"2026-07-07T11:22:31.436194Z","end":"2026-07-07T11:22:31.699776Z","steps":["trace[1492965355] 'agreement among raft nodes before linearized reading' (duration: 263.51441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.69979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.00029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:31.699869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.403017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:31.699921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.718004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.699923Z","caller":"traceutil/trace.go:171","msg":"trace[612673234] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:831; }","duration":"123.472928ms","start":"2026-07-07T11:22:31.576433Z","end":"2026-07-07T11:22:31.699906Z","steps":["trace[612673234] 'agreement among raft nodes before linearized reading' (duration: 123.406886ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:31.699948Z","caller":"traceutil/trace.go:171","msg":"trace[968980010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:831; }","duration":"263.783885ms","start":"2026-07-07T11:22:31.436157Z","end":"2026-07-07T11:22:31.699941Z","steps":["trace[968980010] 'agreement among raft nodes before linearized reading' (duration: 263.739445ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:31.699839Z","caller":"traceutil/trace.go:171","msg":"trace[741587005] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"160.119922ms","start":"2026-07-07T11:22:31.539711Z","end":"2026-07-07T11:22:31.699831Z","steps":["trace[741587005] 'agreement among raft nodes before linearized reading' (duration: 160.060251ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:31.699735Z","caller":"traceutil/trace.go:171","msg":"trace[1619147057] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:831; }","duration":"263.02866ms","start":"2026-07-07T11:22:31.436681Z","end":"2026-07-07T11:22:31.699709Z","steps":["trace[1619147057] 'agreement among raft nodes before linearized reading' (duration: 262.859976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.700117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.241222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.700159Z","caller":"traceutil/trace.go:171","msg":"trace[296715969] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:831; }","duration":"124.317264ms","start":"2026-07-07T11:22:31.575832Z","end":"2026-07-07T11:22:31.70015Z","steps":["trace[296715969] 'agreement among raft nodes before linearized reading' (duration: 124.256603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.700223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.623174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.700294Z","caller":"traceutil/trace.go:171","msg":"trace[1588333431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:831; }","duration":"402.788716ms","start":"2026-07-07T11:22:31.297479Z","end":"2026-07-07T11:22:31.700267Z","steps":["trace[1588333431] 'agreement among raft nodes before linearized reading' (duration: 402.624163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.700358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.506108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:31.700363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:31.297464Z","time spent":"402.888139ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-07-07T11:22:31.70039Z","caller":"traceutil/trace.go:171","msg":"trace[1614223079] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:831; }","duration":"124.556738ms","start":"2026-07-07T11:22:31.575821Z","end":"2026-07-07T11:22:31.700378Z","steps":["trace[1614223079] 'agreement among raft nodes before linearized reading' (duration: 124.486197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.700507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.955439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:31.700558Z","caller":"traceutil/trace.go:171","msg":"trace[1478678389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:831; }","duration":"403.027991ms","start":"2026-07-07T11:22:31.297519Z","end":"2026-07-07T11:22:31.700547Z","steps":["trace[1478678389] 'agreement among raft nodes before linearized reading' (duration: 402.94254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:31.70056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.669522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:22:31.700602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:22:31.297516Z","time spent":"403.076192ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-07-07T11:22:31.700609Z","caller":"traceutil/trace.go:171","msg":"trace[1042924797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"160.775694ms","start":"2026-07-07T11:22:31.53982Z","end":"2026-07-07T11:22:31.700596Z","steps":["trace[1042924797] 'agreement among raft nodes before linearized reading' (duration: 159.572351ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:53.936192Z","caller":"traceutil/trace.go:171","msg":"trace[1828341010] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"100.127021ms","start":"2026-07-07T11:22:53.836043Z","end":"2026-07-07T11:22:53.93617Z","steps":["trace[1828341010] 'process raft request' (duration: 45.089685ms)","trace[1828341010] 'compare' (duration: 54.954704ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:54.16157Z","caller":"traceutil/trace.go:171","msg":"trace[1596577301] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"166.74185ms","start":"2026-07-07T11:22:53.994801Z","end":"2026-07-07T11:22:54.161543Z","steps":["trace[1596577301] 'process raft request' (duration: 166.521676ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:54.161742Z","caller":"traceutil/trace.go:171","msg":"trace[1501362309] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"166.846492ms","start":"2026-07-07T11:22:53.994867Z","end":"2026-07-07T11:22:54.161714Z","steps":["trace[1501362309] 'process raft request' (duration: 166.628668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:56.156868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.331516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T11:22:56.157556Z","caller":"traceutil/trace.go:171","msg":"trace[444464915] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"260.432868ms","start":"2026-07-07T11:22:55.897045Z","end":"2026-07-07T11:22:56.157478Z","steps":["trace[444464915] 'process raft request' (duration: 74.349237ms)","trace[444464915] 'compare' (duration: 185.179543ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:56.157762Z","caller":"traceutil/trace.go:171","msg":"trace[1229328337] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"162.081821ms","start":"2026-07-07T11:22:55.995637Z","end":"2026-07-07T11:22:56.157719Z","steps":["trace[1229328337] 'process raft request' (duration: 161.420807ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:56.157956Z","caller":"traceutil/trace.go:171","msg":"trace[1205081511] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"218.142187ms","start":"2026-07-07T11:22:55.939802Z","end":"2026-07-07T11:22:56.157944Z","steps":["trace[1205081511] 'read index received' (duration: 31.513515ms)","trace[1205081511] 'applied index is now lower than readState.Index' (duration: 186.627102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:22:56.158162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.35185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-07T11:22:56.158135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.37944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:22:56.158194Z","caller":"traceutil/trace.go:171","msg":"trace[731404720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"218.399311ms","start":"2026-07-07T11:22:55.939785Z","end":"2026-07-07T11:22:56.158184Z","steps":["trace[731404720] 'agreement among raft nodes before linearized reading' (duration: 218.246908ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:56.158227Z","caller":"traceutil/trace.go:171","msg":"trace[599508113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"207.492892ms","start":"2026-07-07T11:22:55.950714Z","end":"2026-07-07T11:22:56.158207Z","steps":["trace[599508113] 'agreement among raft nodes before linearized reading' (duration: 207.374849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:56.15834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.078692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:22:56.158365Z","caller":"traceutil/trace.go:171","msg":"trace[667150286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"123.144273ms","start":"2026-07-07T11:22:56.035214Z","end":"2026-07-07T11:22:56.158358Z","steps":["trace[667150286] 'agreement among raft nodes before linearized reading' (duration: 123.066402ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:22:56.279193Z","caller":"traceutil/trace.go:171","msg":"trace[155264054] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"113.432977ms","start":"2026-07-07T11:22:56.165605Z","end":"2026-07-07T11:22:56.279038Z","steps":["trace[155264054] 'read index received' (duration: 47.997941ms)","trace[155264054] 'applied index is now lower than readState.Index' (duration: 65.433826ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:22:56.279235Z","caller":"traceutil/trace.go:171","msg":"trace[651446127] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"113.817355ms","start":"2026-07-07T11:22:56.165375Z","end":"2026-07-07T11:22:56.279192Z","steps":["trace[651446127] 'process raft request' (duration: 112.894517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:56.279524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.820824ms","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-07-07T11:22:56.279645Z","caller":"traceutil/trace.go:171","msg":"trace[207892915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"114.005577ms","start":"2026-07-07T11:22:56.165586Z","end":"2026-07-07T11:22:56.279592Z","steps":["trace[207892915] 'agreement among raft nodes before linearized reading' (duration: 113.716712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:22:56.279758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.818894ms","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-07-07T11:22:56.279983Z","caller":"traceutil/trace.go:171","msg":"trace[1017231463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"114.093529ms","start":"2026-07-07T11:22:56.165711Z","end":"2026-07-07T11:22:56.279804Z","steps":["trace[1017231463] 'agreement among raft nodes before linearized reading' (duration: 113.687151ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:08.57967Z","caller":"traceutil/trace.go:171","msg":"trace[2076111790] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"301.938225ms","start":"2026-07-07T11:23:08.277707Z","end":"2026-07-07T11:23:08.579645Z","steps":["trace[2076111790] 'read index received' (duration: 301.798212ms)","trace[2076111790] 'applied index is now lower than readState.Index' (duration: 139.203µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:23:08.579773Z","caller":"traceutil/trace.go:171","msg":"trace[248095558] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"310.939077ms","start":"2026-07-07T11:23:08.268761Z","end":"2026-07-07T11:23:08.5797Z","steps":["trace[248095558] 'process raft request' (duration: 310.666812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:08.579949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.676002ms","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-07-07T11:23:08.579985Z","caller":"traceutil/trace.go:171","msg":"trace[44193040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"297.772215ms","start":"2026-07-07T11:23:08.282203Z","end":"2026-07-07T11:23:08.579975Z","steps":["trace[44193040] 'agreement among raft nodes before linearized reading' (duration: 297.610982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:08.579974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.268741Z","time spent":"311.127311ms","remote":"127.0.0.1:54466","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-07-07T11:23:08.580028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.179963ms","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-07-07T11:23:08.580067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.204575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:23:08.580083Z","caller":"traceutil/trace.go:171","msg":"trace[1619039604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"223.261284ms","start":"2026-07-07T11:23:08.356808Z","end":"2026-07-07T11:23:08.580069Z","steps":["trace[1619039604] 'agreement among raft nodes before linearized reading' (duration: 223.07193ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:08.580106Z","caller":"traceutil/trace.go:171","msg":"trace[703589272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"259.284645ms","start":"2026-07-07T11:23:08.320811Z","end":"2026-07-07T11:23:08.580095Z","steps":["trace[703589272] 'agreement among raft nodes before linearized reading' (duration: 259.177813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:08.580021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.604915ms","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-07-07T11:23:08.580151Z","caller":"traceutil/trace.go:171","msg":"trace[1391468953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"217.772909ms","start":"2026-07-07T11:23:08.362364Z","end":"2026-07-07T11:23:08.580137Z","steps":["trace[1391468953] 'agreement among raft nodes before linearized reading' (duration: 217.498354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:08.580225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.523076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-07T11:23:08.580252Z","caller":"traceutil/trace.go:171","msg":"trace[412857736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"302.576377ms","start":"2026-07-07T11:23:08.277668Z","end":"2026-07-07T11:23:08.580245Z","steps":["trace[412857736] 'agreement among raft nodes before linearized reading' (duration: 302.109439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:08.580271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.277657Z","time spent":"302.609078ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T11:23:09.450603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119561306858937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T11:23:09.734857Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150259775s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T11:23:09.735229Z","caller":"traceutil/trace.go:171","msg":"trace[571574944] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.150703113s","start":"2026-07-07T11:23:08.584501Z","end":"2026-07-07T11:23:09.735204Z","steps":["trace[571574944] 'process raft request' (duration: 1.15056384s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.73537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.584478Z","time spent":"1.150817826s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:23:09.795505Z","caller":"traceutil/trace.go:171","msg":"trace[573709628] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.209658115s","start":"2026-07-07T11:23:08.585827Z","end":"2026-07-07T11:23:09.795485Z","steps":["trace[573709628] 'process raft request' (duration: 1.209598234s)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:09.795546Z","caller":"traceutil/trace.go:171","msg":"trace[65614270] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.210411269s","start":"2026-07-07T11:23:08.585113Z","end":"2026-07-07T11:23:09.795525Z","steps":["trace[65614270] 'process raft request' (duration: 1.210274866s)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:09.795551Z","caller":"traceutil/trace.go:171","msg":"trace[32655157] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.210482439s","start":"2026-07-07T11:23:08.585031Z","end":"2026-07-07T11:23:09.795513Z","steps":["trace[32655157] 'process raft request' (duration: 1.206999573s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.795642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.585105Z","time spent":"1.21049098s","remote":"127.0.0.1:54466","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-07-07T11:23:09.79564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.585803Z","time spent":"1.209772596s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:23:09.795716Z","caller":"traceutil/trace.go:171","msg":"trace[1672128690] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1084; }","duration":"845.357613ms","start":"2026-07-07T11:23:08.950342Z","end":"2026-07-07T11:23:09.795699Z","steps":["trace[1672128690] 'read index received' (duration: 784.715219ms)","trace[1672128690] 'applied index is now lower than readState.Index' (duration: 60.641114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:23:09.795759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.585008Z","time spent":"1.210615472s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:23:09.795793Z","caller":"traceutil/trace.go:171","msg":"trace[1013776594] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"440.953832ms","start":"2026-07-07T11:23:09.354828Z","end":"2026-07-07T11:23:09.795782Z","steps":["trace[1013776594] 'process raft request' (duration: 440.810009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.795845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:09.354807Z","time spent":"441.011604ms","remote":"127.0.0.1:54466","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-07-07T11:23:09.795949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.604731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T11:23:09.795984Z","caller":"traceutil/trace.go:171","msg":"trace[1979391504] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1053; }","duration":"323.671733ms","start":"2026-07-07T11:23:09.472301Z","end":"2026-07-07T11:23:09.795973Z","steps":["trace[1979391504] 'agreement among raft nodes before linearized reading' (duration: 323.52985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.795971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.607698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:23:09.796015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:09.472287Z","time spent":"323.720873ms","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-07T11:23:09.796047Z","caller":"traceutil/trace.go:171","msg":"trace[420541654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"845.73602ms","start":"2026-07-07T11:23:08.950296Z","end":"2026-07-07T11:23:09.796032Z","steps":["trace[420541654] 'agreement among raft nodes before linearized reading' (duration: 845.527366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.796089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.950272Z","time spent":"845.809462ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T11:23:09.796371Z","caller":"traceutil/trace.go:171","msg":"trace[1259019823] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.209802957s","start":"2026-07-07T11:23:08.585965Z","end":"2026-07-07T11:23:09.795768Z","steps":["trace[1259019823] 'process raft request' (duration: 1.209492651s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:09.79651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:08.585945Z","time spent":"1.210503002s","remote":"127.0.0.1:54466","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-07-07T11:23:30.160845Z","caller":"traceutil/trace.go:171","msg":"trace[118849846] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"112.767844ms","start":"2026-07-07T11:23:30.048058Z","end":"2026-07-07T11:23:30.160826Z","steps":["trace[118849846] 'process raft request' (duration: 112.655841ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:31.338874Z","caller":"traceutil/trace.go:171","msg":"trace[2121976663] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"628.914799ms","start":"2026-07-07T11:23:30.709927Z","end":"2026-07-07T11:23:31.338842Z","steps":["trace[2121976663] 'process raft request' (duration: 628.731996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:31.339067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:30.709904Z","time spent":"629.060882ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:23:31.994371Z","caller":"traceutil/trace.go:171","msg":"trace[1421311146] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"1.042584478s","start":"2026-07-07T11:23:30.95176Z","end":"2026-07-07T11:23:31.994344Z","steps":["trace[1421311146] 'read index received' (duration: 386.986747ms)","trace[1421311146] 'applied index is now lower than readState.Index' (duration: 655.595701ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:23:31.994605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042745431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:31.994709Z","caller":"traceutil/trace.go:171","msg":"trace[253335966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"1.043029637s","start":"2026-07-07T11:23:30.951664Z","end":"2026-07-07T11:23:31.994694Z","steps":["trace[253335966] 'agreement among raft nodes before linearized reading' (duration: 1.042731381s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:31.994791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:30.951643Z","time spent":"1.043132148s","remote":"127.0.0.1:54216","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-07-07T11:23:31.994816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.390079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T11:23:31.994938Z","caller":"traceutil/trace.go:171","msg":"trace[1645054315] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1120; }","duration":"949.688146ms","start":"2026-07-07T11:23:31.045227Z","end":"2026-07-07T11:23:31.994915Z","steps":["trace[1645054315] 'agreement among raft nodes before linearized reading' (duration: 949.350129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:31.995002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:31.045206Z","time spent":"949.777817ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-07T11:23:31.995125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.83989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:23:31.995188Z","caller":"traceutil/trace.go:171","msg":"trace[2104747802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"119.957603ms","start":"2026-07-07T11:23:31.875217Z","end":"2026-07-07T11:23:31.995175Z","steps":["trace[2104747802] 'agreement among raft nodes before linearized reading' (duration: 119.842891ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:32.109037Z","caller":"traceutil/trace.go:171","msg":"trace[560562886] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"108.700506ms","start":"2026-07-07T11:23:32.000311Z","end":"2026-07-07T11:23:32.109011Z","steps":["trace[560562886] 'process raft request' (duration: 82.632245ms)","trace[560562886] 'compare' (duration: 25.903898ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:23:32.109514Z","caller":"traceutil/trace.go:171","msg":"trace[920370638] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"108.95877ms","start":"2026-07-07T11:23:32.000539Z","end":"2026-07-07T11:23:32.109498Z","steps":["trace[920370638] 'process raft request' (duration: 108.646974ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:32.109747Z","caller":"traceutil/trace.go:171","msg":"trace[241378677] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"108.507643ms","start":"2026-07-07T11:23:32.0012Z","end":"2026-07-07T11:23:32.109707Z","steps":["trace[241378677] 'process raft request' (duration: 108.120655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:32.109919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.80835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:32.109959Z","caller":"traceutil/trace.go:171","msg":"trace[93558969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"106.889902ms","start":"2026-07-07T11:23:32.003058Z","end":"2026-07-07T11:23:32.109948Z","steps":["trace[93558969] 'agreement among raft nodes before linearized reading' (duration: 106.787351ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:32.109836Z","caller":"traceutil/trace.go:171","msg":"trace[647354642] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1162; }","duration":"106.26598ms","start":"2026-07-07T11:23:32.003102Z","end":"2026-07-07T11:23:32.109368Z","steps":["trace[647354642] 'read index received' (duration: 23.799927ms)","trace[647354642] 'applied index is now lower than readState.Index' (duration: 82.464783ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:23:32.110234Z","caller":"traceutil/trace.go:171","msg":"trace[969992222] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"109.776667ms","start":"2026-07-07T11:23:32.000416Z","end":"2026-07-07T11:23:32.110193Z","steps":["trace[969992222] 'process raft request' (duration: 108.554313ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:33.832413Z","caller":"traceutil/trace.go:171","msg":"trace[1289727228] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"345.741745ms","start":"2026-07-07T11:23:33.486645Z","end":"2026-07-07T11:23:33.832386Z","steps":["trace[1289727228] 'read index received' (duration: 345.48097ms)","trace[1289727228] 'applied index is now lower than readState.Index' (duration: 258.925µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:23:33.832573Z","caller":"traceutil/trace.go:171","msg":"trace[352068418] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"487.332172ms","start":"2026-07-07T11:23:33.345225Z","end":"2026-07-07T11:23:33.832557Z","steps":["trace[352068418] 'process raft request' (duration: 486.961705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:33.832696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:33.345198Z","time spent":"487.426974ms","remote":"127.0.0.1:54466","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-07-07T11:23:33.894713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.04397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:33.894883Z","caller":"traceutil/trace.go:171","msg":"trace[1185170204] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1126; }","duration":"408.261664ms","start":"2026-07-07T11:23:33.48659Z","end":"2026-07-07T11:23:33.894852Z","steps":["trace[1185170204] 'agreement among raft nodes before linearized reading' (duration: 345.941109ms)","trace[1185170204] 'count revisions from in-memory index tree' (duration: 61.840617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:23:33.894964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:33.486568Z","time spent":"408.380098ms","remote":"127.0.0.1:54636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-07-07T11:23:36.193089Z","caller":"traceutil/trace.go:171","msg":"trace[210694083] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"241.427503ms","start":"2026-07-07T11:23:35.951626Z","end":"2026-07-07T11:23:36.193053Z","steps":["trace[210694083] 'read index received' (duration: 241.24843ms)","trace[210694083] 'applied index is now lower than readState.Index' (duration: 178.193µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:23:36.193184Z","caller":"traceutil/trace.go:171","msg":"trace[1357372070] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"355.194766ms","start":"2026-07-07T11:23:35.837982Z","end":"2026-07-07T11:23:36.193176Z","steps":["trace[1357372070] 'process raft request' (duration: 354.958702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:36.193288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:35.837948Z","time spent":"355.253058ms","remote":"127.0.0.1:54466","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-07-07T11:23:36.193458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.831891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:36.193483Z","caller":"traceutil/trace.go:171","msg":"trace[768965031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"241.894762ms","start":"2026-07-07T11:23:35.951581Z","end":"2026-07-07T11:23:36.193476Z","steps":["trace[768965031] 'agreement among raft nodes before linearized reading' (duration: 241.853511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:36.193704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.502447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-07T11:23:36.193786Z","caller":"traceutil/trace.go:171","msg":"trace[631120244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"126.619569ms","start":"2026-07-07T11:23:36.06715Z","end":"2026-07-07T11:23:36.193769Z","steps":["trace[631120244] 'agreement among raft nodes before linearized reading' (duration: 126.375665ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:23:36.31959Z","caller":"traceutil/trace.go:171","msg":"trace[271846683] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"122.428799ms","start":"2026-07-07T11:23:36.197133Z","end":"2026-07-07T11:23:36.319562Z","steps":["trace[271846683] 'process raft request' (duration: 42.226831ms)","trace[271846683] 'compare' (duration: 80.033165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:23:52.68454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508475689s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T11:23:52.684913Z","caller":"traceutil/trace.go:171","msg":"trace[1410464726] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"1.733827562s","start":"2026-07-07T11:23:50.95078Z","end":"2026-07-07T11:23:52.684607Z","steps":["trace[1410464726] 'read index received' (duration: 225.280612ms)","trace[1410464726] 'applied index is now lower than readState.Index' (duration: 1.50854581s)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:23:52.684994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73420554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:52.685023Z","caller":"traceutil/trace.go:171","msg":"trace[1908512136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"1.734273021s","start":"2026-07-07T11:23:50.950741Z","end":"2026-07-07T11:23:52.685014Z","steps":["trace[1908512136] 'agreement among raft nodes before linearized reading' (duration: 1.73421884s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.685046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:50.950722Z","time spent":"1.734317492s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T11:23:52.69281Z","caller":"traceutil/trace.go:171","msg":"trace[1970556411] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"450.542192ms","start":"2026-07-07T11:23:52.242237Z","end":"2026-07-07T11:23:52.692779Z","steps":["trace[1970556411] 'process raft request' (duration: 450.369648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.692986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:52.242221Z","time spent":"450.674245ms","remote":"127.0.0.1:54466","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-07-07T11:23:52.69332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.156232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:23:52.693364Z","caller":"traceutil/trace.go:171","msg":"trace[1136705832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"263.217742ms","start":"2026-07-07T11:23:52.43013Z","end":"2026-07-07T11:23:52.693348Z","steps":["trace[1136705832] 'agreement among raft nodes before linearized reading' (duration: 263.10983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.217477ms","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-07-07T11:23:52.693407Z","caller":"traceutil/trace.go:171","msg":"trace[1835331213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"242.274769ms","start":"2026-07-07T11:23:52.451121Z","end":"2026-07-07T11:23:52.693396Z","steps":["trace[1835331213] 'agreement among raft nodes before linearized reading' (duration: 242.142407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.418218ms","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-07-07T11:23:52.69345Z","caller":"traceutil/trace.go:171","msg":"trace[1424681944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"260.473369ms","start":"2026-07-07T11:23:52.432965Z","end":"2026-07-07T11:23:52.693438Z","steps":["trace[1424681944] 'agreement among raft nodes before linearized reading' (duration: 260.393757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.87058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:23:52.693543Z","caller":"traceutil/trace.go:171","msg":"trace[844614868] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1238; }","duration":"612.901681ms","start":"2026-07-07T11:23:52.080633Z","end":"2026-07-07T11:23:52.693534Z","steps":["trace[844614868] 'agreement among raft nodes before linearized reading' (duration: 612.85638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.957539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-07T11:23:52.69357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:52.080595Z","time spent":"612.967982ms","remote":"127.0.0.1:54366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-07T11:23:52.69359Z","caller":"traceutil/trace.go:171","msg":"trace[991661558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"302.01331ms","start":"2026-07-07T11:23:52.391561Z","end":"2026-07-07T11:23:52.693574Z","steps":["trace[991661558] 'agreement among raft nodes before linearized reading' (duration: 301.863918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.353344ms","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-07-07T11:23:52.69364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:52.391528Z","time spent":"302.100492ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T11:23:52.693651Z","caller":"traceutil/trace.go:171","msg":"trace[1851411383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"306.474306ms","start":"2026-07-07T11:23:52.387159Z","end":"2026-07-07T11:23:52.693634Z","steps":["trace[1851411383] 'agreement among raft nodes before linearized reading' (duration: 306.255421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:23:52.693697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:23:52.387145Z","time spent":"306.543928ms","remote":"127.0.0.1:54466","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-07-07T11:24:04.972204Z","caller":"traceutil/trace.go:171","msg":"trace[716592879] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"186.062145ms","start":"2026-07-07T11:24:04.786108Z","end":"2026-07-07T11:24:04.97217Z","steps":["trace[716592879] 'process raft request' (duration: 180.614821ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:04.9722Z","caller":"traceutil/trace.go:171","msg":"trace[866407254] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"183.501277ms","start":"2026-07-07T11:24:04.788635Z","end":"2026-07-07T11:24:04.972137Z","steps":["trace[866407254] 'read index received' (duration: 178.077273ms)","trace[866407254] 'applied index is now lower than readState.Index' (duration: 5.421814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:24:04.973025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.913686ms","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-07-07T11:24:04.973106Z","caller":"traceutil/trace.go:171","msg":"trace[171481881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"181.023148ms","start":"2026-07-07T11:24:04.792064Z","end":"2026-07-07T11:24:04.973087Z","steps":["trace[171481881] 'agreement among raft nodes before linearized reading' (duration: 180.784144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:04.97332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.029051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:24:04.973395Z","caller":"traceutil/trace.go:171","msg":"trace[1079175401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"178.118602ms","start":"2026-07-07T11:24:04.795256Z","end":"2026-07-07T11:24:04.973374Z","steps":["trace[1079175401] 'agreement among raft nodes before linearized reading' (duration: 177.832287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:04.972348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.702611ms","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-07-07T11:24:04.973856Z","caller":"traceutil/trace.go:171","msg":"trace[955607849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"185.24294ms","start":"2026-07-07T11:24:04.788599Z","end":"2026-07-07T11:24:04.973842Z","steps":["trace[955607849] 'agreement among raft nodes before linearized reading' (duration: 183.64805ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:08.515938Z","caller":"traceutil/trace.go:171","msg":"trace[1947286067] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"428.523268ms","start":"2026-07-07T11:24:08.087391Z","end":"2026-07-07T11:24:08.515914Z","steps":["trace[1947286067] 'process raft request' (duration: 428.402696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:08.516072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:08.087376Z","time spent":"428.636881ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:24:08.741058Z","caller":"traceutil/trace.go:171","msg":"trace[138956464] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"215.3667ms","start":"2026-07-07T11:24:08.525665Z","end":"2026-07-07T11:24:08.741032Z","steps":["trace[138956464] 'process raft request' (duration: 127.15952ms)","trace[138956464] 'compare' (duration: 88.087368ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:24:08.767124Z","caller":"traceutil/trace.go:171","msg":"trace[1866053162] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"236.759072ms","start":"2026-07-07T11:24:08.530353Z","end":"2026-07-07T11:24:08.767112Z","steps":["trace[1866053162] 'process raft request' (duration: 236.727951ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:08.767358Z","caller":"traceutil/trace.go:171","msg":"trace[2021309866] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"240.919332ms","start":"2026-07-07T11:24:08.526432Z","end":"2026-07-07T11:24:08.767351Z","steps":["trace[2021309866] 'process raft request' (duration: 240.580806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:09.869981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.424276ms","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-07-07T11:24:09.870123Z","caller":"traceutil/trace.go:171","msg":"trace[1045295585] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1317; }","duration":"401.573259ms","start":"2026-07-07T11:24:09.468528Z","end":"2026-07-07T11:24:09.870101Z","steps":["trace[1045295585] 'range keys from in-memory index tree' (duration: 401.408166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:11.465869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.878606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T11:24:11.544291Z","caller":"traceutil/trace.go:171","msg":"trace[1657872658] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"744.093078ms","start":"2026-07-07T11:24:10.800172Z","end":"2026-07-07T11:24:11.544265Z","steps":["trace[1657872658] 'process raft request' (duration: 743.839434ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:11.544456Z","caller":"traceutil/trace.go:171","msg":"trace[978474220] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1368; }","duration":"601.280576ms","start":"2026-07-07T11:24:10.943143Z","end":"2026-07-07T11:24:11.544423Z","steps":["trace[978474220] 'read index received' (duration: 55.986149ms)","trace[978474220] 'applied index is now lower than readState.Index' (duration: 545.291937ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T11:24:11.544503Z","caller":"traceutil/trace.go:171","msg":"trace[15797279] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"730.479106ms","start":"2026-07-07T11:24:10.813994Z","end":"2026-07-07T11:24:11.544474Z","steps":["trace[15797279] 'process raft request' (duration: 730.22123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:11.544639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:10.813966Z","time spent":"730.598629ms","remote":"127.0.0.1:54466","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-07-07T11:24:11.544632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:10.800146Z","time spent":"744.298602ms","remote":"127.0.0.1:54466","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-07-07T11:24:11.544731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.562052ms","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-07-07T11:24:11.544787Z","caller":"traceutil/trace.go:171","msg":"trace[1579996261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"601.667785ms","start":"2026-07-07T11:24:10.943106Z","end":"2026-07-07T11:24:11.544774Z","steps":["trace[1579996261] 'agreement among raft nodes before linearized reading' (duration: 601.455231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:11.544831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:10.943091Z","time spent":"601.730576ms","remote":"127.0.0.1:54466","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-07-07T11:24:12.047296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.555328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T11:24:12.047473Z","caller":"traceutil/trace.go:171","msg":"trace[1818116294] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"498.559258ms","start":"2026-07-07T11:24:11.548901Z","end":"2026-07-07T11:24:12.04746Z","steps":["trace[1818116294] 'process raft request' (duration: 498.493197ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:12.04745Z","caller":"traceutil/trace.go:171","msg":"trace[1286933360] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"925.622908ms","start":"2026-07-07T11:24:11.12178Z","end":"2026-07-07T11:24:12.047403Z","steps":["trace[1286933360] 'process raft request' (duration: 466.906057ms)","trace[1286933360] 'compare' (duration: 458.343793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:24:12.047527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.548868Z","time spent":"498.628859ms","remote":"127.0.0.1:54466","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-07-07T11:24:12.047573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.12176Z","time spent":"925.75407ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:24:12.048011Z","caller":"traceutil/trace.go:171","msg":"trace[1832368706] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"503.466762ms","start":"2026-07-07T11:24:11.544527Z","end":"2026-07-07T11:24:12.047994Z","steps":["trace[1832368706] 'read index received' (duration: 44.14084ms)","trace[1832368706] 'applied index is now lower than readState.Index' (duration: 459.324052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:24:12.048097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.721903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:24:12.048123Z","caller":"traceutil/trace.go:171","msg":"trace[892432325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"934.782554ms","start":"2026-07-07T11:24:11.113332Z","end":"2026-07-07T11:24:12.048115Z","steps":["trace[892432325] 'agreement among raft nodes before linearized reading' (duration: 934.681603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.048149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.113322Z","time spent":"934.821635ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T11:24:12.048117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.245693ms","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":"warn","ts":"2026-07-07T11:24:12.04816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.108852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-07T11:24:12.048179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.06222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:24:12.048206Z","caller":"traceutil/trace.go:171","msg":"trace[1799559404] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1321; }","duration":"841.381345ms","start":"2026-07-07T11:24:11.206801Z","end":"2026-07-07T11:24:12.048182Z","steps":["trace[1799559404] 'agreement among raft nodes before linearized reading' (duration: 841.204311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.048228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.772674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-07T11:24:12.048303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.206782Z","time spent":"841.505328ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-07T11:24:12.048322Z","caller":"traceutil/trace.go:171","msg":"trace[2092590706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"866.902846ms","start":"2026-07-07T11:24:11.181407Z","end":"2026-07-07T11:24:12.04831Z","steps":["trace[2092590706] 'agreement among raft nodes before linearized reading' (duration: 866.59109ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:12.048225Z","caller":"traceutil/trace.go:171","msg":"trace[1556070586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"949.145301ms","start":"2026-07-07T11:24:11.099069Z","end":"2026-07-07T11:24:12.048215Z","steps":["trace[1556070586] 'agreement among raft nodes before linearized reading' (duration: 949.025469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.048411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.181359Z","time spent":"867.020648ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T11:24:12.048478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.099011Z","time spent":"949.453618ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T11:24:12.048226Z","caller":"traceutil/trace.go:171","msg":"trace[1751821684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"934.195613ms","start":"2026-07-07T11:24:11.114015Z","end":"2026-07-07T11:24:12.048211Z","steps":["trace[1751821684] 'agreement among raft nodes before linearized reading' (duration: 933.99194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.04858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:11.114004Z","time spent":"934.566031ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-07T11:24:12.363933Z","caller":"traceutil/trace.go:171","msg":"trace[1886942883] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"310.652696ms","start":"2026-07-07T11:24:12.053252Z","end":"2026-07-07T11:24:12.363905Z","steps":["trace[1886942883] 'process raft request' (duration: 250.710501ms)","trace[1886942883] 'compare' (duration: 59.831953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:24:12.36409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.05323Z","time spent":"310.786949ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:24:12.364658Z","caller":"traceutil/trace.go:171","msg":"trace[440108377] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"310.498113ms","start":"2026-07-07T11:24:12.05414Z","end":"2026-07-07T11:24:12.364638Z","steps":["trace[440108377] 'process raft request' (duration: 310.471252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.364788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.054129Z","time spent":"310.592074ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:24:12.364934Z","caller":"traceutil/trace.go:171","msg":"trace[1098488517] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"311.551993ms","start":"2026-07-07T11:24:12.053375Z","end":"2026-07-07T11:24:12.364927Z","steps":["trace[1098488517] 'process raft request' (duration: 311.040124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.364975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.053364Z","time spent":"311.590475ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T11:24:12.365002Z","caller":"traceutil/trace.go:171","msg":"trace[272728734] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"311.577764ms","start":"2026-07-07T11:24:12.053411Z","end":"2026-07-07T11:24:12.364989Z","steps":["trace[272728734] 'process raft request' (duration: 311.149455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.365056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.053379Z","time spent":"311.648816ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:24:12.36504Z","caller":"traceutil/trace.go:171","msg":"trace[1312608734] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"311.612846ms","start":"2026-07-07T11:24:12.053403Z","end":"2026-07-07T11:24:12.365016Z","steps":["trace[1312608734] 'process raft request' (duration: 311.179977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.365163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.053379Z","time spent":"311.707857ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T11:24:12.365241Z","caller":"traceutil/trace.go:171","msg":"trace[877617285] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"311.85556ms","start":"2026-07-07T11:24:12.053378Z","end":"2026-07-07T11:24:12.365234Z","steps":["trace[877617285] 'process raft request' (duration: 311.138316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:12.365291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:12.053355Z","time spent":"311.90569ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T11:24:22.559177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110436235s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T11:24:22.559403Z","caller":"traceutil/trace.go:171","msg":"trace[1802547044] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"1.54679054s","start":"2026-07-07T11:24:21.01259Z","end":"2026-07-07T11:24:22.55938Z","steps":["trace[1802547044] 'read index received' (duration: 436.050069ms)","trace[1802547044] 'applied index is now lower than readState.Index' (duration: 1.110738671s)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:24:22.559574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546974283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T11:24:22.559607Z","caller":"traceutil/trace.go:171","msg":"trace[333285457] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1393; }","duration":"1.547057855s","start":"2026-07-07T11:24:21.012536Z","end":"2026-07-07T11:24:22.559594Z","steps":["trace[333285457] 'agreement among raft nodes before linearized reading' (duration: 1.546953863s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.559643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.01249Z","time spent":"1.547144697s","remote":"127.0.0.1:54416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-07-07T11:24:22.566006Z","caller":"traceutil/trace.go:171","msg":"trace[232176469] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"983.943229ms","start":"2026-07-07T11:24:21.581969Z","end":"2026-07-07T11:24:22.565913Z","steps":["trace[232176469] 'process raft request' (duration: 983.800426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.566125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.581946Z","time spent":"984.111361ms","remote":"127.0.0.1:54466","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-07-07T11:24:22.567004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.338727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-07-07T11:24:22.567053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.888531ms","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-07-07T11:24:22.567055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.843507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T11:24:22.56701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.449447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T11:24:22.56716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.346608ms","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-07-07T11:24:22.567189Z","caller":"traceutil/trace.go:171","msg":"trace[1537553348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"162.419299ms","start":"2026-07-07T11:24:22.404761Z","end":"2026-07-07T11:24:22.56718Z","steps":["trace[1537553348] 'agreement among raft nodes before linearized reading' (duration: 162.340918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.567234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.508713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-07T11:24:22.567282Z","caller":"traceutil/trace.go:171","msg":"trace[522771948] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1395; }","duration":"200.582105ms","start":"2026-07-07T11:24:22.366686Z","end":"2026-07-07T11:24:22.567268Z","steps":["trace[522771948] 'agreement among raft nodes before linearized reading' (duration: 200.470333ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:22.567122Z","caller":"traceutil/trace.go:171","msg":"trace[1208778418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"158.996813ms","start":"2026-07-07T11:24:22.408109Z","end":"2026-07-07T11:24:22.567106Z","steps":["trace[1208778418] 'agreement among raft nodes before linearized reading' (duration: 158.81869ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:22.567364Z","caller":"traceutil/trace.go:171","msg":"trace[672988071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"616.661561ms","start":"2026-07-07T11:24:21.950504Z","end":"2026-07-07T11:24:22.567166Z","steps":["trace[672988071] 'agreement among raft nodes before linearized reading' (duration: 616.453297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.567397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.367934ms","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-07-07T11:24:22.567464Z","caller":"traceutil/trace.go:171","msg":"trace[823158878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"386.461595ms","start":"2026-07-07T11:24:22.180991Z","end":"2026-07-07T11:24:22.567452Z","steps":["trace[823158878] 'agreement among raft nodes before linearized reading' (duration: 386.270882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.56752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087965702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-07T11:24:22.567596Z","caller":"traceutil/trace.go:171","msg":"trace[1187138530] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1395; }","duration":"1.088086815s","start":"2026-07-07T11:24:21.4795Z","end":"2026-07-07T11:24:22.567587Z","steps":["trace[1187138530] 'agreement among raft nodes before linearized reading' (duration: 1.087943422s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.56762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.479475Z","time spent":"1.088137165s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-07T11:24:22.567533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:22.180974Z","time spent":"386.546397ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-07T11:24:22.567078Z","caller":"traceutil/trace.go:171","msg":"trace[595641145] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1395; }","duration":"177.45812ms","start":"2026-07-07T11:24:22.389602Z","end":"2026-07-07T11:24:22.56706Z","steps":["trace[595641145] 'agreement among raft nodes before linearized reading' (duration: 177.239746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.567602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.950467Z","time spent":"617.119399ms","remote":"127.0.0.1:54216","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-07-07T11:24:22.567134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.442431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T11:24:22.567843Z","caller":"traceutil/trace.go:171","msg":"trace[1226602244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1395; }","duration":"162.229936ms","start":"2026-07-07T11:24:22.405598Z","end":"2026-07-07T11:24:22.567828Z","steps":["trace[1226602244] 'agreement among raft nodes before linearized reading' (duration: 161.261967ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:22.567206Z","caller":"traceutil/trace.go:171","msg":"trace[1851447684] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1395; }","duration":"599.024751ms","start":"2026-07-07T11:24:21.968132Z","end":"2026-07-07T11:24:22.567157Z","steps":["trace[1851447684] 'agreement among raft nodes before linearized reading' (duration: 598.774876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.568039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.96811Z","time spent":"599.903379ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-07T11:24:22.568139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.551331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:24:22.568498Z","caller":"traceutil/trace.go:171","msg":"trace[815335026] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"633.876333ms","start":"2026-07-07T11:24:21.933018Z","end":"2026-07-07T11:24:22.566894Z","steps":["trace[815335026] 'process raft request' (duration: 633.636388ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:22.568583Z","caller":"traceutil/trace.go:171","msg":"trace[613881697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1395; }","duration":"164.080942ms","start":"2026-07-07T11:24:22.404473Z","end":"2026-07-07T11:24:22.568554Z","steps":["trace[613881697] 'agreement among raft nodes before linearized reading' (duration: 162.722106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.567871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482515902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T11:24:22.569756Z","caller":"traceutil/trace.go:171","msg":"trace[820232366] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1395; }","duration":"1.484437889s","start":"2026-07-07T11:24:21.085301Z","end":"2026-07-07T11:24:22.569739Z","steps":["trace[820232366] 'agreement among raft nodes before linearized reading' (duration: 1.481515843s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:24:22.569814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.08524Z","time spent":"1.484550201s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T11:24:22.571216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T11:24:21.932989Z","time spent":"635.558845ms","remote":"127.0.0.1:54466","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-07-07T11:24:48.941092Z","caller":"traceutil/trace.go:171","msg":"trace[52738458] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"102.629487ms","start":"2026-07-07T11:24:48.838444Z","end":"2026-07-07T11:24:48.941074Z","steps":["trace[52738458] 'process raft request' (duration: 101.591867ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:48.941483Z","caller":"traceutil/trace.go:171","msg":"trace[558847424] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"102.76407ms","start":"2026-07-07T11:24:48.838676Z","end":"2026-07-07T11:24:48.94144Z","steps":["trace[558847424] 'process raft request' (duration: 102.716599ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:48.941468Z","caller":"traceutil/trace.go:171","msg":"trace[1725098780] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"102.912833ms","start":"2026-07-07T11:24:48.838526Z","end":"2026-07-07T11:24:48.941439Z","steps":["trace[1725098780] 'process raft request' (duration: 102.511516ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:24:48.94154Z","caller":"traceutil/trace.go:171","msg":"trace[2017039143] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"102.848932ms","start":"2026-07-07T11:24:48.838637Z","end":"2026-07-07T11:24:48.941485Z","steps":["trace[2017039143] 'process raft request' (duration: 102.607578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:25:00.918196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.537437ms","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-07-07T11:25:00.918311Z","caller":"traceutil/trace.go:171","msg":"trace[287133271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1572; }","duration":"112.702339ms","start":"2026-07-07T11:25:00.805591Z","end":"2026-07-07T11:25:00.918293Z","steps":["trace[287133271] 'count revisions from in-memory index tree' (duration: 112.472655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:25:00.918479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.623255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:25:00.918564Z","caller":"traceutil/trace.go:171","msg":"trace[2027155625] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1572; }","duration":"102.749948ms","start":"2026-07-07T11:25:00.815792Z","end":"2026-07-07T11:25:00.918542Z","steps":["trace[2027155625] 'count revisions from in-memory index tree' (duration: 102.536314ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:25:01.111544Z","caller":"traceutil/trace.go:171","msg":"trace[275440779] linearizableReadLoop","detail":"{readStateIndex:1637; appliedIndex:1636; }","duration":"110.492968ms","start":"2026-07-07T11:25:01.001026Z","end":"2026-07-07T11:25:01.111519Z","steps":["trace[275440779] 'read index received' (duration: 20.377393ms)","trace[275440779] 'applied index is now lower than readState.Index' (duration: 90.114505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:25:01.111755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.686061ms","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-07-07T11:25:01.111833Z","caller":"traceutil/trace.go:171","msg":"trace[1975231377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1575; }","duration":"110.859664ms","start":"2026-07-07T11:25:01.000954Z","end":"2026-07-07T11:25:01.111813Z","steps":["trace[1975231377] 'agreement among raft nodes before linearized reading' (duration: 110.652691ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:25:01.111649Z","caller":"traceutil/trace.go:171","msg":"trace[122962076] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"111.443916ms","start":"2026-07-07T11:25:01.000164Z","end":"2026-07-07T11:25:01.111608Z","steps":["trace[122962076] 'process raft request' (duration: 21.31668ms)","trace[122962076] 'compare' (duration: 89.928152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:25:01.112139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.434159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T11:25:01.112259Z","caller":"traceutil/trace.go:171","msg":"trace[472129626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1575; }","duration":"108.555211ms","start":"2026-07-07T11:25:01.003648Z","end":"2026-07-07T11:25:01.112203Z","steps":["trace[472129626] 'agreement among raft nodes before linearized reading' (duration: 108.246644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:25:01.112244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.146222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-07T11:25:01.112909Z","caller":"traceutil/trace.go:171","msg":"trace[1447235720] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1575; }","duration":"109.876056ms","start":"2026-07-07T11:25:01.002983Z","end":"2026-07-07T11:25:01.112859Z","steps":["trace[1447235720] 'agreement among raft nodes before linearized reading' (duration: 108.975658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T11:25:41.747176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.732844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T11:25:41.747288Z","caller":"traceutil/trace.go:171","msg":"trace[1973773824] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"269.583941ms","start":"2026-07-07T11:25:41.477686Z","end":"2026-07-07T11:25:41.74727Z","steps":["trace[1973773824] 'process raft request' (duration: 119.676785ms)","trace[1973773824] 'compare' (duration: 149.489058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:25:43.330904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.183205ms","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-07-07T11:25:43.331032Z","caller":"traceutil/trace.go:171","msg":"trace[1975825254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1766; }","duration":"187.401138ms","start":"2026-07-07T11:25:43.143608Z","end":"2026-07-07T11:25:43.331009Z","steps":["trace[1975825254] 'range keys from in-memory index tree' (duration: 186.903209ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:27:27.422171Z","caller":"traceutil/trace.go:171","msg":"trace[52240951] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"105.533042ms","start":"2026-07-07T11:27:27.316612Z","end":"2026-07-07T11:27:27.422145Z","steps":["trace[52240951] 'process raft request' (duration: 105.345188ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:27:58.748548Z","caller":"traceutil/trace.go:171","msg":"trace[262850761] linearizableReadLoop","detail":"{readStateIndex:2497; appliedIndex:2496; }","duration":"124.869103ms","start":"2026-07-07T11:27:58.623655Z","end":"2026-07-07T11:27:58.748525Z","steps":["trace[262850761] 'read index received' (duration: 124.668798ms)","trace[262850761] 'applied index is now lower than readState.Index' (duration: 199.365µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T11:27:58.748687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.016005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T11:27:58.74872Z","caller":"traceutil/trace.go:171","msg":"trace[1387067930] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:2399; }","duration":"125.096677ms","start":"2026-07-07T11:27:58.623612Z","end":"2026-07-07T11:27:58.748709Z","steps":["trace[1387067930] 'agreement among raft nodes before linearized reading' (duration: 125.013626ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T11:27:58.748989Z","caller":"traceutil/trace.go:171","msg":"trace[443368580] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"168.180355ms","start":"2026-07-07T11:27:58.580786Z","end":"2026-07-07T11:27:58.748966Z","steps":["trace[443368580] 'process raft request' (duration: 167.593264ms)"],"step_count":1}