{"level":"warn","ts":"2026-05-09T12:53:40.622964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:53:40.623098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-05-09T12:53:40.623256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:53:40.623277Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-09T12:53:40.623298Z","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-05-09T12:53:40.624095Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-09T12:53:40.624221Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-05-09T12:53:40.627635Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.199454ms"} {"level":"info","ts":"2026-05-09T12:53:40.635526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-09T12:53:40.635657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T12:53:40.6357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-09T12:53:40.635734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T12:53:40.635762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.635824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-09T12:53:40.641813Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T12:53:40.643492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T12:53:40.644561Z","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-05-09T12:53:40.646592Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T12:53:40.647129Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T12:53:40.647838Z","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-05-09T12:53:40.648223Z","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-05-09T12:53:40.648233Z","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-05-09T12:53:40.648682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-09T12:53:40.648891Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-09T12:53:40.653719Z","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-05-09T12:53:40.653846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-09T12:53:40.653887Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-09T12:53:40.654299Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T12:53:40.654236Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T12:53:40.736195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.736293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.736351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.736399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.736438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.736476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.736515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.737571Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.73849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:53:40.739153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:53:40.739564Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T12:53:40.739645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T12:53:40.738487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T12:53:40.743356Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T12:53:40.743397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-05-09T12:53:40.743668Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.743814Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.744164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:42.582553Z","caller":"traceutil/trace.go:171","msg":"trace[960919966] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"231.493655ms","start":"2026-05-09T12:53:42.351035Z","end":"2026-05-09T12:53:42.582529Z","steps":["trace[960919966] 'process raft request' (duration: 221.999706ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.241749Z","caller":"traceutil/trace.go:171","msg":"trace[953817168] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"886.956747ms","start":"2026-05-09T12:53:42.354714Z","end":"2026-05-09T12:53:43.24167Z","steps":["trace[953817168] 'process raft request' (duration: 847.172323ms)","trace[953817168] 'compare' (duration: 39.432114ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:53:43.241928Z","caller":"traceutil/trace.go:171","msg":"trace[946612954] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"848.462016ms","start":"2026-05-09T12:53:42.393419Z","end":"2026-05-09T12:53:43.241881Z","steps":["trace[946612954] 'process raft request' (duration: 848.155179ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.241982Z","caller":"traceutil/trace.go:171","msg":"trace[872152245] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"848.457097ms","start":"2026-05-09T12:53:42.393479Z","end":"2026-05-09T12:53:43.241936Z","steps":["trace[872152245] 'process raft request' (duration: 848.154419ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242066Z","caller":"traceutil/trace.go:171","msg":"trace[1319090659] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"848.557088ms","start":"2026-05-09T12:53:42.393482Z","end":"2026-05-09T12:53:43.242039Z","steps":["trace[1319090659] 'process raft request' (duration: 848.182969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242088Z","caller":"traceutil/trace.go:171","msg":"trace[485251798] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"848.887638ms","start":"2026-05-09T12:53:42.393193Z","end":"2026-05-09T12:53:43.24208Z","steps":["trace[485251798] 'process raft request' (duration: 848.750254ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242122Z","caller":"traceutil/trace.go:171","msg":"trace[1561589818] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"848.58023ms","start":"2026-05-09T12:53:42.393536Z","end":"2026-05-09T12:53:43.242117Z","steps":["trace[1561589818] 'process raft request' (duration: 848.487917ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242188Z","caller":"traceutil/trace.go:171","msg":"trace[2053207685] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"848.595671ms","start":"2026-05-09T12:53:42.393513Z","end":"2026-05-09T12:53:43.242109Z","steps":["trace[2053207685] 'process raft request' (duration: 848.19465ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242188Z","caller":"traceutil/trace.go:171","msg":"trace[1320207081] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"884.799711ms","start":"2026-05-09T12:53:42.357369Z","end":"2026-05-09T12:53:43.242168Z","steps":["trace[1320207081] 'read index received' (duration: 215.778303ms)","trace[1320207081] 'applied index is now lower than readState.Index' (duration: 669.020238ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:53:43.242255Z","caller":"traceutil/trace.go:171","msg":"trace[65928171] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"839.404388ms","start":"2026-05-09T12:53:42.402842Z","end":"2026-05-09T12:53:43.242246Z","steps":["trace[65928171] 'process raft request' (duration: 839.230784ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242282Z","caller":"traceutil/trace.go:171","msg":"trace[1852736905] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"837.328474ms","start":"2026-05-09T12:53:42.404945Z","end":"2026-05-09T12:53:43.242274Z","steps":["trace[1852736905] 'process raft request' (duration: 837.17559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.242421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393409Z","time spent":"848.60193ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393504Z","time spent":"848.731584ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.028556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T12:53:43.242503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.383285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.242507Z","caller":"traceutil/trace.go:171","msg":"trace[556627721] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"885.143589ms","start":"2026-05-09T12:53:42.357348Z","end":"2026-05-09T12:53:43.242492Z","steps":["trace[556627721] 'agreement among raft nodes before linearized reading' (duration: 884.942864ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.242539Z","caller":"traceutil/trace.go:171","msg":"trace[1631162059] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"872.423735ms","start":"2026-05-09T12:53:42.370105Z","end":"2026-05-09T12:53:43.242529Z","steps":["trace[1631162059] 'agreement among raft nodes before linearized reading' (duration: 872.357024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.242533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.354695Z","time spent":"887.216113ms","remote":"127.0.0.1:37990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:53:43.242569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393462Z","time spent":"848.58926ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.370091Z","time spent":"872.468916ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-09T12:53:43.242562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.357342Z","time spent":"885.199661ms","remote":"127.0.0.1:38332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-09T12:53:43.242643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393443Z","time spent":"848.641351ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.83995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.242762Z","caller":"traceutil/trace.go:171","msg":"trace[1501287104] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"799.900491ms","start":"2026-05-09T12:53:42.442855Z","end":"2026-05-09T12:53:43.242755Z","steps":["trace[1501287104] 'agreement among raft nodes before linearized reading' (duration: 799.85954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.242776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.402807Z","time spent":"839.47203ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.442835Z","time spent":"799.943264ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-09T12:53:43.242801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393172Z","time spent":"848.931549ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.254095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.242919Z","caller":"traceutil/trace.go:171","msg":"trace[1473739205] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"831.309587ms","start":"2026-05-09T12:53:42.411603Z","end":"2026-05-09T12:53:43.242913Z","steps":["trace[1473739205] 'agreement among raft nodes before linearized reading' (duration: 831.272886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.242925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.28917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T12:53:43.242939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.411589Z","time spent":"831.344598ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-09T12:53:43.242935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393518Z","time spent":"848.620902ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.242969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.713922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.242993Z","caller":"traceutil/trace.go:171","msg":"trace[1647002238] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"835.785014ms","start":"2026-05-09T12:53:42.407204Z","end":"2026-05-09T12:53:43.242989Z","steps":["trace[1647002238] 'agreement among raft nodes before linearized reading' (duration: 835.755543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.243011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.407191Z","time spent":"835.817165ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-09T12:53:43.242733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.40493Z","time spent":"837.374796ms","remote":"127.0.0.1:38112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:53:43.243038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.713297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T12:53:43.243084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.39303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.2431Z","caller":"traceutil/trace.go:171","msg":"trace[1691590804] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"849.408781ms","start":"2026-05-09T12:53:42.393687Z","end":"2026-05-09T12:53:43.243096Z","steps":["trace[1691590804] 'agreement among raft nodes before linearized reading' (duration: 849.38597ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.243093Z","caller":"traceutil/trace.go:171","msg":"trace[300961848] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"847.791639ms","start":"2026-05-09T12:53:42.395285Z","end":"2026-05-09T12:53:43.243077Z","steps":["trace[300961848] 'agreement among raft nodes before linearized reading' (duration: 847.687556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.243113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.393679Z","time spent":"849.430752ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-09T12:53:43.242951Z","caller":"traceutil/trace.go:171","msg":"trace[1576544897] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:11; }","duration":"851.317931ms","start":"2026-05-09T12:53:42.391627Z","end":"2026-05-09T12:53:43.242945Z","steps":["trace[1576544897] 'agreement among raft nodes before linearized reading' (duration: 851.27214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.243132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.395229Z","time spent":"847.894091ms","remote":"127.0.0.1:38016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-09T12:53:43.243153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:42.391534Z","time spent":"851.605319ms","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-05-09T12:53:43.492638Z","caller":"traceutil/trace.go:171","msg":"trace[1174477672] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"245.316507ms","start":"2026-05-09T12:53:43.247292Z","end":"2026-05-09T12:53:43.492608Z","steps":["trace[1174477672] 'read index received' (duration: 241.869627ms)","trace[1174477672] 'applied index is now lower than readState.Index' (duration: 3.44577ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:53:43.492776Z","caller":"traceutil/trace.go:171","msg":"trace[1819213254] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"245.605755ms","start":"2026-05-09T12:53:43.247136Z","end":"2026-05-09T12:53:43.492741Z","steps":["trace[1819213254] 'process raft request' (duration: 242.046471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.492842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.527784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3169"} {"level":"info","ts":"2026-05-09T12:53:43.492877Z","caller":"traceutil/trace.go:171","msg":"trace[1024618614] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"245.612975ms","start":"2026-05-09T12:53:43.247254Z","end":"2026-05-09T12:53:43.492866Z","steps":["trace[1024618614] 'agreement among raft nodes before linearized reading' (duration: 245.481042ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.493946Z","caller":"traceutil/trace.go:171","msg":"trace[1274529709] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"244.775243ms","start":"2026-05-09T12:53:43.249147Z","end":"2026-05-09T12:53:43.493922Z","steps":["trace[1274529709] 'process raft request' (duration: 244.66126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.494246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.796067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.49431Z","caller":"traceutil/trace.go:171","msg":"trace[1298819548] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"246.941201ms","start":"2026-05-09T12:53:43.247352Z","end":"2026-05-09T12:53:43.494294Z","steps":["trace[1298819548] 'process raft request' (duration: 246.320934ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.494314Z","caller":"traceutil/trace.go:171","msg":"trace[1058463809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"246.91181ms","start":"2026-05-09T12:53:43.247386Z","end":"2026-05-09T12:53:43.494298Z","steps":["trace[1058463809] 'agreement among raft nodes before linearized reading' (duration: 246.5325ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.494369Z","caller":"traceutil/trace.go:171","msg":"trace[94089175] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"245.863712ms","start":"2026-05-09T12:53:43.24849Z","end":"2026-05-09T12:53:43.494353Z","steps":["trace[94089175] 'process raft request' (duration: 245.265416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.494523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.578906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T12:53:43.494521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.804373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T12:53:43.494602Z","caller":"traceutil/trace.go:171","msg":"trace[96171989] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:19; }","duration":"202.711549ms","start":"2026-05-09T12:53:43.291879Z","end":"2026-05-09T12:53:43.494591Z","steps":["trace[96171989] 'agreement among raft nodes before linearized reading' (duration: 202.597626ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.494682Z","caller":"traceutil/trace.go:171","msg":"trace[118040935] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19; }","duration":"200.023059ms","start":"2026-05-09T12:53:43.294637Z","end":"2026-05-09T12:53:43.49466Z","steps":["trace[118040935] 'agreement among raft nodes before linearized reading' (duration: 199.803283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.494854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.312981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-8zh65\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-09T12:53:43.494912Z","caller":"traceutil/trace.go:171","msg":"trace[1399291150] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"245.552095ms","start":"2026-05-09T12:53:43.249343Z","end":"2026-05-09T12:53:43.494895Z","steps":["trace[1399291150] 'process raft request' (duration: 244.485076ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.494899Z","caller":"traceutil/trace.go:171","msg":"trace[229198310] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-8zh65; range_end:; response_count:1; response_revision:19; }","duration":"247.384853ms","start":"2026-05-09T12:53:43.247502Z","end":"2026-05-09T12:53:43.494887Z","steps":["trace[229198310] 'agreement among raft nodes before linearized reading' (duration: 247.287019ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.495038Z","caller":"traceutil/trace.go:171","msg":"trace[1169555351] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"245.650157ms","start":"2026-05-09T12:53:43.249375Z","end":"2026-05-09T12:53:43.495025Z","steps":["trace[1169555351] 'process raft request' (duration: 244.468076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:43.495085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.477706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-09T12:53:43.495129Z","caller":"traceutil/trace.go:171","msg":"trace[7385567] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"188.541437ms","start":"2026-05-09T12:53:43.306575Z","end":"2026-05-09T12:53:43.495116Z","steps":["trace[7385567] 'agreement among raft nodes before linearized reading' (duration: 188.466355ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.495271Z","caller":"traceutil/trace.go:171","msg":"trace[1131265947] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"242.619037ms","start":"2026-05-09T12:53:43.252636Z","end":"2026-05-09T12:53:43.495255Z","steps":["trace[1131265947] 'process raft request' (duration: 241.244011ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.496789Z","caller":"traceutil/trace.go:171","msg":"trace[1356766912] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"244.443455ms","start":"2026-05-09T12:53:43.24988Z","end":"2026-05-09T12:53:43.494324Z","steps":["trace[1356766912] 'process raft request' (duration: 243.979592ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:43.496979Z","caller":"traceutil/trace.go:171","msg":"trace[455493218] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"247.900247ms","start":"2026-05-09T12:53:43.249041Z","end":"2026-05-09T12:53:43.496942Z","steps":["trace[455493218] 'process raft request' (duration: 244.731143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.806959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.827623ms","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-05-09T12:54:10.807124Z","caller":"traceutil/trace.go:171","msg":"trace[716137204] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:482; }","duration":"122.027548ms","start":"2026-05-09T12:54:10.685079Z","end":"2026-05-09T12:54:10.807106Z","steps":["trace[716137204] 'range keys from in-memory index tree' (duration: 121.810311ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.807794Z","caller":"traceutil/trace.go:171","msg":"trace[152158318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:482; }","duration":"510.767485ms","start":"2026-05-09T12:54:10.296971Z","end":"2026-05-09T12:54:10.807738Z","steps":["trace[152158318] 'process raft request' (duration: 510.502088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.808318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.296961Z","time spent":"510.97639ms","remote":"127.0.0.1:38456","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-05-09T12:54:10.82056Z","caller":"traceutil/trace.go:171","msg":"trace[1058341132] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"519.95012ms","start":"2026-05-09T12:54:10.30057Z","end":"2026-05-09T12:54:10.82052Z","steps":["trace[1058341132] 'process raft request' (duration: 519.735805ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.820623Z","caller":"traceutil/trace.go:171","msg":"trace[60720555] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"518.665216ms","start":"2026-05-09T12:54:10.301915Z","end":"2026-05-09T12:54:10.82058Z","steps":["trace[60720555] 'process raft request' (duration: 518.497742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.820742Z","caller":"traceutil/trace.go:171","msg":"trace[1557260234] transaction","detail":"{read_only:false; number_of_response:0; response_revision:484; }","duration":"503.764368ms","start":"2026-05-09T12:54:10.316959Z","end":"2026-05-09T12:54:10.820724Z","steps":["trace[1557260234] 'process raft request' (duration: 503.488421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.82074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.300561Z","time spent":"520.084994ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:54:10.821064Z","caller":"traceutil/trace.go:171","msg":"trace[1366845240] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"489.137328ms","start":"2026-05-09T12:54:10.331908Z","end":"2026-05-09T12:54:10.821045Z","steps":["trace[1366845240] 'process raft request' (duration: 489.062776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.82081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.301908Z","time spent":"518.798309ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:54:10.821135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.331897Z","time spent":"489.198409ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:54:10.820827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.316948Z","time spent":"503.830939ms","remote":"127.0.0.1:38456","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-05-09T12:54:10.821209Z","caller":"traceutil/trace.go:171","msg":"trace[1157013893] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"489.23718ms","start":"2026-05-09T12:54:10.331963Z","end":"2026-05-09T12:54:10.8212Z","steps":["trace[1157013893] 'process raft request' (duration: 489.041325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.331959Z","time spent":"489.27397ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:54:10.821342Z","caller":"traceutil/trace.go:171","msg":"trace[82661562] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"485.410127ms","start":"2026-05-09T12:54:10.335924Z","end":"2026-05-09T12:54:10.821334Z","steps":["trace[82661562] 'process raft request' (duration: 485.13679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.335915Z","time spent":"485.444448ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:54:10.820937Z","caller":"traceutil/trace.go:171","msg":"trace[1242770186] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"502.478543ms","start":"2026-05-09T12:54:10.318421Z","end":"2026-05-09T12:54:10.8209Z","steps":["trace[1242770186] 'process raft request' (duration: 502.083343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.318399Z","time spent":"503.016187ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:54:10.820982Z","caller":"traceutil/trace.go:171","msg":"trace[2147318829] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"502.458073ms","start":"2026-05-09T12:54:10.318513Z","end":"2026-05-09T12:54:10.820971Z","steps":["trace[2147318829] 'process raft request' (duration: 502.327599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.318507Z","time spent":"502.955955ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:54:10.821013Z","caller":"traceutil/trace.go:171","msg":"trace[1639798667] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"494.249104ms","start":"2026-05-09T12:54:10.326759Z","end":"2026-05-09T12:54:10.821008Z","steps":["trace[1639798667] 'process raft request' (duration: 494.169302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.326753Z","time spent":"494.756386ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:54:10.821005Z","caller":"traceutil/trace.go:171","msg":"trace[132264839] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"494.376127ms","start":"2026-05-09T12:54:10.326619Z","end":"2026-05-09T12:54:10.820995Z","steps":["trace[132264839] 'process raft request' (duration: 494.275524ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.821511Z","caller":"traceutil/trace.go:171","msg":"trace[1385809540] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"475.852182ms","start":"2026-05-09T12:54:10.345634Z","end":"2026-05-09T12:54:10.821487Z","steps":["trace[1385809540] 'process raft request' (duration: 475.622996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.326603Z","time spent":"494.958153ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:54:10.821626Z","caller":"traceutil/trace.go:171","msg":"trace[683607857] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:500; }","duration":"135.93943ms","start":"2026-05-09T12:54:10.685675Z","end":"2026-05-09T12:54:10.821615Z","steps":["trace[683607857] 'read index received' (duration: 121.31824ms)","trace[683607857] 'applied index is now lower than readState.Index' (duration: 14.6206ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:10.821663Z","caller":"traceutil/trace.go:171","msg":"trace[1504606228] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"392.897899ms","start":"2026-05-09T12:54:10.428759Z","end":"2026-05-09T12:54:10.821657Z","steps":["trace[1504606228] 'process raft request' (duration: 392.647292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:10.821693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.42873Z","time spent":"392.950481ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:54:10.821653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:10.345616Z","time spent":"475.962244ms","remote":"127.0.0.1:37976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:54:10.821766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.098694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:54:10.821786Z","caller":"traceutil/trace.go:171","msg":"trace[882163060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"136.147875ms","start":"2026-05-09T12:54:10.685633Z","end":"2026-05-09T12:54:10.821781Z","steps":["trace[882163060] 'agreement among raft nodes before linearized reading' (duration: 136.090164ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.972477Z","caller":"traceutil/trace.go:171","msg":"trace[784104336] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"130.703679ms","start":"2026-05-09T12:54:10.841758Z","end":"2026-05-09T12:54:10.972462Z","steps":["trace[784104336] 'process raft request' (duration: 129.810486ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:10.972606Z","caller":"traceutil/trace.go:171","msg":"trace[618735962] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"127.73874ms","start":"2026-05-09T12:54:10.844448Z","end":"2026-05-09T12:54:10.972187Z","steps":["trace[618735962] 'process raft request' (duration: 127.299708ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:11.030516Z","caller":"traceutil/trace.go:171","msg":"trace[923409115] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"104.923551ms","start":"2026-05-09T12:54:10.925568Z","end":"2026-05-09T12:54:11.030491Z","steps":["trace[923409115] 'process raft request' (duration: 104.683155ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:11.030542Z","caller":"traceutil/trace.go:171","msg":"trace[1716380117] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"120.330432ms","start":"2026-05-09T12:54:10.910191Z","end":"2026-05-09T12:54:11.030522Z","steps":["trace[1716380117] 'process raft request' (duration: 119.87851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.815819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.525396ms","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-05-09T12:54:16.81592Z","caller":"traceutil/trace.go:171","msg":"trace[387270308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"571.63512ms","start":"2026-05-09T12:54:16.244262Z","end":"2026-05-09T12:54:16.815897Z","steps":["trace[387270308] 'range keys from in-memory index tree' (duration: 571.369662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.815963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:16.244221Z","time spent":"571.732262ms","remote":"127.0.0.1:38218","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-05-09T12:54:16.81749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.002323ms","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-05-09T12:54:16.817593Z","caller":"traceutil/trace.go:171","msg":"trace[924929467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"462.119296ms","start":"2026-05-09T12:54:16.355448Z","end":"2026-05-09T12:54:16.817567Z","steps":["trace[924929467] 'range keys from in-memory index tree' (duration: 459.883486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.81765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:16.355385Z","time spent":"462.251739ms","remote":"127.0.0.1:38218","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-05-09T12:54:16.971962Z","caller":"traceutil/trace.go:171","msg":"trace[603377120] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"437.121058ms","start":"2026-05-09T12:54:16.534813Z","end":"2026-05-09T12:54:16.971934Z","steps":["trace[603377120] 'process raft request' (duration: 436.880252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.972306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:16.534785Z","time spent":"437.316004ms","remote":"127.0.0.1:38218","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-05-09T12:54:16.984295Z","caller":"traceutil/trace.go:171","msg":"trace[1690342439] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"404.939139ms","start":"2026-05-09T12:54:16.579337Z","end":"2026-05-09T12:54:16.984276Z","steps":["trace[1690342439] 'process raft request' (duration: 404.474986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.984424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:16.579277Z","time spent":"405.083763ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:54:16.984599Z","caller":"traceutil/trace.go:171","msg":"trace[751508279] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"163.471555ms","start":"2026-05-09T12:54:16.821105Z","end":"2026-05-09T12:54:16.984577Z","steps":["trace[751508279] 'process raft request' (duration: 163.129815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.984842Z","caller":"traceutil/trace.go:171","msg":"trace[1105459158] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"298.133868ms","start":"2026-05-09T12:54:16.686697Z","end":"2026-05-09T12:54:16.984831Z","steps":["trace[1105459158] 'read index received' (duration: 284.975807ms)","trace[1105459158] 'applied index is now lower than readState.Index' (duration: 13.157251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:54:16.984903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.206991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:54:16.984923Z","caller":"traceutil/trace.go:171","msg":"trace[784324955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"298.287002ms","start":"2026-05-09T12:54:16.68663Z","end":"2026-05-09T12:54:16.984917Z","steps":["trace[784324955] 'agreement among raft nodes before linearized reading' (duration: 298.239321ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.985028Z","caller":"traceutil/trace.go:171","msg":"trace[2095071327] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"161.929742ms","start":"2026-05-09T12:54:16.823092Z","end":"2026-05-09T12:54:16.985021Z","steps":["trace[2095071327] 'process raft request' (duration: 161.409788ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:17.749257Z","caller":"traceutil/trace.go:171","msg":"trace[867095120] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"272.955407ms","start":"2026-05-09T12:54:17.476279Z","end":"2026-05-09T12:54:17.749235Z","steps":["trace[867095120] 'process raft request' (duration: 243.102929ms)","trace[867095120] 'compare' (duration: 29.048626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:54:19.237593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298886053s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:54:19.237743Z","caller":"traceutil/trace.go:171","msg":"trace[466645990] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"1.153311836s","start":"2026-05-09T12:54:18.084409Z","end":"2026-05-09T12:54:19.237721Z","steps":["trace[466645990] 'read index received' (duration: 73.092µs)","trace[466645990] 'applied index is now lower than readState.Index' (duration: 1.153237094s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:19.237781Z","caller":"traceutil/trace.go:171","msg":"trace[262051464] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.42648497s","start":"2026-05-09T12:54:17.81125Z","end":"2026-05-09T12:54:19.237735Z","steps":["trace[262051464] 'process raft request' (duration: 126.577869ms)","trace[262051464] 'compare' (duration: 1.298675167s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:54:19.237942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:17.811214Z","time spent":"1.426645983s","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:54:19.23812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.816402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T12:54:19.238153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.248733ms","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-05-09T12:54:19.238296Z","caller":"traceutil/trace.go:171","msg":"trace[569254269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"249.378188ms","start":"2026-05-09T12:54:18.988874Z","end":"2026-05-09T12:54:19.238252Z","steps":["trace[569254269] 'agreement among raft nodes before linearized reading' (duration: 249.086959ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:19.238188Z","caller":"traceutil/trace.go:171","msg":"trace[81935723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"121.921945ms","start":"2026-05-09T12:54:19.11624Z","end":"2026-05-09T12:54:19.238162Z","steps":["trace[81935723] 'agreement among raft nodes before linearized reading' (duration: 121.699129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:19.238237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153737157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-99hb5\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-05-09T12:54:19.238424Z","caller":"traceutil/trace.go:171","msg":"trace[1530031335] range","detail":"{range_begin:/registry/pods/kube-system/cilium-99hb5; range_end:; response_count:1; response_revision:580; }","duration":"1.154057658s","start":"2026-05-09T12:54:18.084346Z","end":"2026-05-09T12:54:19.238404Z","steps":["trace[1530031335] 'agreement among raft nodes before linearized reading' (duration: 1.153477602s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:19.238289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.768066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:54:19.238398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.112946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-09T12:54:19.238524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:18.084326Z","time spent":"1.15418414s","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-99hb5\" "} {"level":"info","ts":"2026-05-09T12:54:19.238616Z","caller":"traceutil/trace.go:171","msg":"trace[1651851122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"250.383384ms","start":"2026-05-09T12:54:18.988213Z","end":"2026-05-09T12:54:19.238597Z","steps":["trace[1651851122] 'agreement among raft nodes before linearized reading' (duration: 249.946503ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:19.238559Z","caller":"traceutil/trace.go:171","msg":"trace[1162855421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"553.082094ms","start":"2026-05-09T12:54:18.685463Z","end":"2026-05-09T12:54:19.238545Z","steps":["trace[1162855421] 'agreement among raft nodes before linearized reading' (duration: 552.430017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:19.239065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:18.685444Z","time spent":"553.606168ms","remote":"127.0.0.1:37932","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-05-09T12:54:35.171817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.893357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:54:35.17192Z","caller":"traceutil/trace.go:171","msg":"trace[1838813416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"248.048521ms","start":"2026-05-09T12:54:34.92385Z","end":"2026-05-09T12:54:35.171898Z","steps":["trace[1838813416] 'range keys from in-memory index tree' (duration: 247.721292ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:35.657394Z","caller":"traceutil/trace.go:171","msg":"trace[1716621321] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"470.16451ms","start":"2026-05-09T12:54:35.187207Z","end":"2026-05-09T12:54:35.657372Z","steps":["trace[1716621321] 'read index received' (duration: 470.109599ms)","trace[1716621321] 'applied index is now lower than readState.Index' (duration: 53.941µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:35.657527Z","caller":"traceutil/trace.go:171","msg":"trace[113770540] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"481.145465ms","start":"2026-05-09T12:54:35.176332Z","end":"2026-05-09T12:54:35.657477Z","steps":["trace[113770540] 'process raft request' (duration: 480.900738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:35.657692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.442758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jdp5z\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-05-09T12:54:35.657789Z","caller":"traceutil/trace.go:171","msg":"trace[1790282264] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jdp5z; range_end:; response_count:1; response_revision:742; }","duration":"470.579882ms","start":"2026-05-09T12:54:35.187184Z","end":"2026-05-09T12:54:35.657764Z","steps":["trace[1790282264] 'agreement among raft nodes before linearized reading' (duration: 470.316595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:35.65784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:35.187174Z","time spent":"470.656914ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jdp5z\" "} {"level":"warn","ts":"2026-05-09T12:54:35.657819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.527256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T12:54:35.657881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.701213ms","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-05-09T12:54:35.657919Z","caller":"traceutil/trace.go:171","msg":"trace[1289008059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"289.797636ms","start":"2026-05-09T12:54:35.368109Z","end":"2026-05-09T12:54:35.657907Z","steps":["trace[1289008059] 'agreement among raft nodes before linearized reading' (duration: 289.657602ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:35.657912Z","caller":"traceutil/trace.go:171","msg":"trace[1827965233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"290.662419ms","start":"2026-05-09T12:54:35.367224Z","end":"2026-05-09T12:54:35.657887Z","steps":["trace[1827965233] 'agreement among raft nodes before linearized reading' (duration: 290.452844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:35.658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.059779ms","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-05-09T12:54:35.65808Z","caller":"traceutil/trace.go:171","msg":"trace[728238745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"288.158482ms","start":"2026-05-09T12:54:35.36991Z","end":"2026-05-09T12:54:35.658069Z","steps":["trace[728238745] 'agreement among raft nodes before linearized reading' (duration: 287.790262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:35.657724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:35.176303Z","time spent":"481.332719ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:54:35.789828Z","caller":"traceutil/trace.go:171","msg":"trace[1023652450] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"127.338579ms","start":"2026-05-09T12:54:35.662462Z","end":"2026-05-09T12:54:35.7898Z","steps":["trace[1023652450] 'process raft request' (duration: 127.154213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:35.790061Z","caller":"traceutil/trace.go:171","msg":"trace[766626910] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"126.806435ms","start":"2026-05-09T12:54:35.663231Z","end":"2026-05-09T12:54:35.790037Z","steps":["trace[766626910] 'process raft request' (duration: 126.516437ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:35.790236Z","caller":"traceutil/trace.go:171","msg":"trace[477003967] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"125.86405ms","start":"2026-05-09T12:54:35.66435Z","end":"2026-05-09T12:54:35.790214Z","steps":["trace[477003967] 'process raft request' (duration: 125.597304ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:35.790376Z","caller":"traceutil/trace.go:171","msg":"trace[1520188909] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:769; }","duration":"104.436527ms","start":"2026-05-09T12:54:35.685922Z","end":"2026-05-09T12:54:35.790358Z","steps":["trace[1520188909] 'read index received' (duration: 25.12062ms)","trace[1520188909] 'applied index is now lower than readState.Index' (duration: 79.314777ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:35.790407Z","caller":"traceutil/trace.go:171","msg":"trace[565224667] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"124.370219ms","start":"2026-05-09T12:54:35.666004Z","end":"2026-05-09T12:54:35.790374Z","steps":["trace[565224667] 'process raft request' (duration: 124.148504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:35.790457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.53638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:54:35.790483Z","caller":"traceutil/trace.go:171","msg":"trace[800976856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"104.625822ms","start":"2026-05-09T12:54:35.685849Z","end":"2026-05-09T12:54:35.790475Z","steps":["trace[800976856] 'agreement among raft nodes before linearized reading' (duration: 104.571981ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:39.668303Z","caller":"traceutil/trace.go:171","msg":"trace[432777961] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"114.872327ms","start":"2026-05-09T12:54:39.553408Z","end":"2026-05-09T12:54:39.66828Z","steps":["trace[432777961] 'process raft request' (duration: 113.85033ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:39.687238Z","caller":"traceutil/trace.go:171","msg":"trace[732850026] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"133.031481ms","start":"2026-05-09T12:54:39.554183Z","end":"2026-05-09T12:54:39.687214Z","steps":["trace[732850026] 'process raft request' (duration: 132.742224ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:39.687259Z","caller":"traceutil/trace.go:171","msg":"trace[1623843103] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"131.058349ms","start":"2026-05-09T12:54:39.556169Z","end":"2026-05-09T12:54:39.687227Z","steps":["trace[1623843103] 'process raft request' (duration: 130.880944ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:39.819104Z","caller":"traceutil/trace.go:171","msg":"trace[1987257900] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"101.575361ms","start":"2026-05-09T12:54:39.717516Z","end":"2026-05-09T12:54:39.819091Z","steps":["trace[1987257900] 'process raft request' (duration: 99.529997ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:39.819105Z","caller":"traceutil/trace.go:171","msg":"trace[1677090131] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"100.648297ms","start":"2026-05-09T12:54:39.718432Z","end":"2026-05-09T12:54:39.81908Z","steps":["trace[1677090131] 'process raft request' (duration: 100.498483ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:40.261715Z","caller":"traceutil/trace.go:171","msg":"trace[661062581] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"166.949887ms","start":"2026-05-09T12:54:40.094736Z","end":"2026-05-09T12:54:40.261686Z","steps":["trace[661062581] 'process raft request' (duration: 166.189226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:40.704434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.772587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:54:40.704593Z","caller":"traceutil/trace.go:171","msg":"trace[50545450] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"118.72602ms","start":"2026-05-09T12:54:40.585843Z","end":"2026-05-09T12:54:40.704569Z","steps":["trace[50545450] 'read index received' (duration: 13.749197ms)","trace[50545450] 'applied index is now lower than readState.Index' (duration: 104.974943ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:40.704664Z","caller":"traceutil/trace.go:171","msg":"trace[624563781] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"178.98309ms","start":"2026-05-09T12:54:40.525639Z","end":"2026-05-09T12:54:40.704623Z","steps":["trace[624563781] 'process raft request' (duration: 73.907254ms)","trace[624563781] 'compare' (duration: 104.49963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:54:40.705312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.46462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-05-09T12:54:40.705365Z","caller":"traceutil/trace.go:171","msg":"trace[1480689292] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:793; }","duration":"119.560283ms","start":"2026-05-09T12:54:40.58579Z","end":"2026-05-09T12:54:40.70535Z","steps":["trace[1480689292] 'agreement among raft nodes before linearized reading' (duration: 118.902995ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:11.668492Z","caller":"traceutil/trace.go:171","msg":"trace[1914939720] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"101.639758ms","start":"2026-05-09T12:55:11.566833Z","end":"2026-05-09T12:55:11.668472Z","steps":["trace[1914939720] 'read index received' (duration: 101.519015ms)","trace[1914939720] 'applied index is now lower than readState.Index' (duration: 119.943µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:55:11.668595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.743291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:55:11.668618Z","caller":"traceutil/trace.go:171","msg":"trace[893625623] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:958; }","duration":"101.814982ms","start":"2026-05-09T12:55:11.566796Z","end":"2026-05-09T12:55:11.668611Z","steps":["trace[893625623] 'agreement among raft nodes before linearized reading' (duration: 101.753301ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:11.668683Z","caller":"traceutil/trace.go:171","msg":"trace[491659499] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"205.752924ms","start":"2026-05-09T12:55:11.462905Z","end":"2026-05-09T12:55:11.668658Z","steps":["trace[491659499] 'process raft request' (duration: 205.449497ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:32.380383Z","caller":"traceutil/trace.go:171","msg":"trace[2103952131] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"310.677561ms","start":"2026-05-09T12:55:32.069688Z","end":"2026-05-09T12:55:32.380365Z","steps":["trace[2103952131] 'read index received' (duration: 310.504617ms)","trace[2103952131] 'applied index is now lower than readState.Index' (duration: 172.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:32.380465Z","caller":"traceutil/trace.go:171","msg":"trace[1616635125] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"611.916067ms","start":"2026-05-09T12:55:31.76854Z","end":"2026-05-09T12:55:32.380457Z","steps":["trace[1616635125] 'process raft request' (duration: 611.707191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:32.38057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:31.768521Z","time spent":"611.967397ms","remote":"127.0.0.1:38218","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-05-09T12:55:32.380664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.960309ms","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-05-09T12:55:32.380744Z","caller":"traceutil/trace.go:171","msg":"trace[1483313252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"311.096063ms","start":"2026-05-09T12:55:32.069632Z","end":"2026-05-09T12:55:32.380728Z","steps":["trace[1483313252] 'agreement among raft nodes before linearized reading' (duration: 310.855825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:32.380809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:32.069609Z","time spent":"311.172895ms","remote":"127.0.0.1:38218","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-05-09T12:55:32.381004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.025105ms","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-05-09T12:55:32.381047Z","caller":"traceutil/trace.go:171","msg":"trace[363224866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"225.099027ms","start":"2026-05-09T12:55:32.155937Z","end":"2026-05-09T12:55:32.381036Z","steps":["trace[363224866] 'agreement among raft nodes before linearized reading' (duration: 225.005334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:32.38118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.172314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:55:32.381211Z","caller":"traceutil/trace.go:171","msg":"trace[1371087598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"142.234026ms","start":"2026-05-09T12:55:32.238969Z","end":"2026-05-09T12:55:32.381203Z","steps":["trace[1371087598] 'agreement among raft nodes before linearized reading' (duration: 142.158744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:32.381329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.518292ms","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-05-09T12:55:32.381365Z","caller":"traceutil/trace.go:171","msg":"trace[615842227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"153.570694ms","start":"2026-05-09T12:55:32.227786Z","end":"2026-05-09T12:55:32.381357Z","steps":["trace[615842227] 'agreement among raft nodes before linearized reading' (duration: 153.44511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:32.381346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.077093ms","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-05-09T12:55:32.381406Z","caller":"traceutil/trace.go:171","msg":"trace[832368807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"159.181666ms","start":"2026-05-09T12:55:32.222209Z","end":"2026-05-09T12:55:32.381391Z","steps":["trace[832368807] 'agreement among raft nodes before linearized reading' (duration: 159.01959ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:35.989612Z","caller":"traceutil/trace.go:171","msg":"trace[968911629] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"169.012991ms","start":"2026-05-09T12:55:35.820571Z","end":"2026-05-09T12:55:35.989584Z","steps":["trace[968911629] 'process raft request' (duration: 168.826055ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:46.237797Z","caller":"traceutil/trace.go:171","msg":"trace[689123571] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"130.062907ms","start":"2026-05-09T12:55:46.107707Z","end":"2026-05-09T12:55:46.23777Z","steps":["trace[689123571] 'process raft request' (duration: 129.888403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:46.411454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.842075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:55:46.411539Z","caller":"traceutil/trace.go:171","msg":"trace[390922542] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1122; }","duration":"128.948497ms","start":"2026-05-09T12:55:46.282571Z","end":"2026-05-09T12:55:46.411519Z","steps":["trace[390922542] 'count revisions from in-memory index tree' (duration: 128.721491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.045067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265717246207159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:55:49.545493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265717246207159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:55:49.622818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155985448s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:55:49.623123Z","caller":"traceutil/trace.go:171","msg":"trace[1008392072] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"1.15642521s","start":"2026-05-09T12:55:48.466673Z","end":"2026-05-09T12:55:49.623098Z","steps":["trace[1008392072] 'process raft request' (duration: 1.156293056s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.623244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.466651Z","time spent":"1.156519172s","remote":"127.0.0.1:38218","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-05-09T12:55:49.647241Z","caller":"traceutil/trace.go:171","msg":"trace[919977669] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"983.178986ms","start":"2026-05-09T12:55:48.664037Z","end":"2026-05-09T12:55:49.647216Z","steps":["trace[919977669] 'process raft request' (duration: 982.922879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.647452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.664017Z","time spent":"983.36116ms","remote":"127.0.0.1:38218","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-05-09T12:55:49.649872Z","caller":"traceutil/trace.go:171","msg":"trace[1341775592] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1174; }","duration":"1.104857327s","start":"2026-05-09T12:55:48.544991Z","end":"2026-05-09T12:55:49.649848Z","steps":["trace[1341775592] 'read index received' (duration: 1.078657558s)","trace[1341775592] 'applied index is now lower than readState.Index' (duration: 26.198509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:55:49.650278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105274977s","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-05-09T12:55:49.650345Z","caller":"traceutil/trace.go:171","msg":"trace[1279279609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1130; }","duration":"1.10538955s","start":"2026-05-09T12:55:48.544939Z","end":"2026-05-09T12:55:49.650328Z","steps":["trace[1279279609] 'agreement among raft nodes before linearized reading' (duration: 1.105177955s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.650394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.544918Z","time spent":"1.105463972s","remote":"127.0.0.1:38218","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-05-09T12:55:49.650692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068973235s","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-05-09T12:55:49.650738Z","caller":"traceutil/trace.go:171","msg":"trace[1226351304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1130; }","duration":"1.069055237s","start":"2026-05-09T12:55:48.581669Z","end":"2026-05-09T12:55:49.650725Z","steps":["trace[1226351304] 'agreement among raft nodes before linearized reading' (duration: 1.068947064s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.650778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.581649Z","time spent":"1.069118979s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:55:49.651184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.099886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:55:49.651231Z","caller":"traceutil/trace.go:171","msg":"trace[1319473306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"965.172048ms","start":"2026-05-09T12:55:48.686045Z","end":"2026-05-09T12:55:49.651217Z","steps":["trace[1319473306] 'agreement among raft nodes before linearized reading' (duration: 965.114226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.651269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.686032Z","time spent":"965.227481ms","remote":"127.0.0.1:37932","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-05-09T12:55:49.651699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.83885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:55:49.651745Z","caller":"traceutil/trace.go:171","msg":"trace[987851973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1130; }","duration":"991.944383ms","start":"2026-05-09T12:55:48.659789Z","end":"2026-05-09T12:55:49.651733Z","steps":["trace[987851973] 'agreement among raft nodes before linearized reading' (duration: 991.793609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.651768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.659768Z","time spent":"991.995054ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T12:55:49.651914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.890963ms","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-05-09T12:55:49.651937Z","caller":"traceutil/trace.go:171","msg":"trace[96405989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1130; }","duration":"997.934785ms","start":"2026-05-09T12:55:48.653996Z","end":"2026-05-09T12:55:49.651931Z","steps":["trace[96405989] 'agreement among raft nodes before linearized reading' (duration: 997.875263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.651952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.653982Z","time spent":"997.965486ms","remote":"127.0.0.1:38218","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-05-09T12:55:49.652074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049935991s","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-05-09T12:55:49.652096Z","caller":"traceutil/trace.go:171","msg":"trace[967419899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1130; }","duration":"1.049979652s","start":"2026-05-09T12:55:48.602111Z","end":"2026-05-09T12:55:49.65209Z","steps":["trace[967419899] 'agreement among raft nodes before linearized reading' (duration: 1.049921041s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:49.652112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:48.602098Z","time spent":"1.050010133s","remote":"127.0.0.1:38218","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-05-09T12:56:01.878607Z","caller":"traceutil/trace.go:171","msg":"trace[206807315] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"153.421517ms","start":"2026-05-09T12:56:01.72516Z","end":"2026-05-09T12:56:01.878581Z","steps":["trace[206807315] 'read index received' (duration: 153.313654ms)","trace[206807315] 'applied index is now lower than readState.Index' (duration: 107.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:01.878687Z","caller":"traceutil/trace.go:171","msg":"trace[1852483834] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"162.722353ms","start":"2026-05-09T12:56:01.715925Z","end":"2026-05-09T12:56:01.878648Z","steps":["trace[1852483834] 'process raft request' (duration: 162.543329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:01.87878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.601721ms","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-05-09T12:56:01.87881Z","caller":"traceutil/trace.go:171","msg":"trace[1490932451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1169; }","duration":"153.675943ms","start":"2026-05-09T12:56:01.725125Z","end":"2026-05-09T12:56:01.878801Z","steps":["trace[1490932451] 'agreement among raft nodes before linearized reading' (duration: 153.55868ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:04.35822Z","caller":"traceutil/trace.go:171","msg":"trace[1941525025] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"123.763186ms","start":"2026-05-09T12:56:04.234433Z","end":"2026-05-09T12:56:04.358196Z","steps":["trace[1941525025] 'read index received' (duration: 87.584351ms)","trace[1941525025] 'applied index is now lower than readState.Index' (duration: 36.177354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:04.358376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.927579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-09T12:56:04.358427Z","caller":"traceutil/trace.go:171","msg":"trace[1639187481] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1195; }","duration":"124.023822ms","start":"2026-05-09T12:56:04.234389Z","end":"2026-05-09T12:56:04.358412Z","steps":["trace[1639187481] 'agreement among raft nodes before linearized reading' (duration: 123.895059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:05.006482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.092317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:05.006577Z","caller":"traceutil/trace.go:171","msg":"trace[1958837960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"319.228981ms","start":"2026-05-09T12:56:04.687324Z","end":"2026-05-09T12:56:05.006553Z","steps":["trace[1958837960] 'range keys from in-memory index tree' (duration: 318.948844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:05.006641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:04.687296Z","time spent":"319.334074ms","remote":"127.0.0.1:37932","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-05-09T12:56:05.006919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.296695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gs9lp\" ","response":"range_response_count:1 size:11053"} {"level":"info","ts":"2026-05-09T12:56:05.007011Z","caller":"traceutil/trace.go:171","msg":"trace[491471548] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gs9lp; range_end:; response_count:1; response_revision:1197; }","duration":"528.418128ms","start":"2026-05-09T12:56:04.478568Z","end":"2026-05-09T12:56:05.006986Z","steps":["trace[491471548] 'range keys from in-memory index tree' (duration: 528.0835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:05.007073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:04.478552Z","time spent":"528.507871ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11076,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gs9lp\" "} {"level":"info","ts":"2026-05-09T12:56:05.61192Z","caller":"traceutil/trace.go:171","msg":"trace[1798265681] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"583.590383ms","start":"2026-05-09T12:56:05.0283Z","end":"2026-05-09T12:56:05.611891Z","steps":["trace[1798265681] 'process raft request' (duration: 583.354877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:05.612093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.028283Z","time spent":"583.726018ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11367,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:06.186968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265717246207315,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T12:56:06.37937Z","caller":"traceutil/trace.go:171","msg":"trace[1144905507] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"754.7589ms","start":"2026-05-09T12:56:05.624511Z","end":"2026-05-09T12:56:06.379269Z","steps":["trace[1144905507] 'process raft request' (duration: 754.704268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.379556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.624485Z","time spent":"754.973776ms","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:06.37957Z","caller":"traceutil/trace.go:171","msg":"trace[443849204] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"757.916093ms","start":"2026-05-09T12:56:05.621626Z","end":"2026-05-09T12:56:06.379542Z","steps":["trace[443849204] 'process raft request' (duration: 757.41093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.379719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.621616Z","time spent":"758.020546ms","remote":"127.0.0.1:38232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:06.498789Z","caller":"traceutil/trace.go:171","msg":"trace[1577140034] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1249; }","duration":"812.661487ms","start":"2026-05-09T12:56:05.686104Z","end":"2026-05-09T12:56:06.498765Z","steps":["trace[1577140034] 'read index received' (duration: 692.97006ms)","trace[1577140034] 'applied index is now lower than readState.Index' (duration: 119.690167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:06.499236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.579118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T12:56:06.49927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.242064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:56:06.49933Z","caller":"traceutil/trace.go:171","msg":"trace[1585327665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"601.704672ms","start":"2026-05-09T12:56:05.897589Z","end":"2026-05-09T12:56:06.499294Z","steps":["trace[1585327665] 'agreement among raft nodes before linearized reading' (duration: 601.492827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.499372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.89757Z","time spent":"601.794695ms","remote":"127.0.0.1:38218","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-05-09T12:56:06.499412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.307074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:06.499444Z","caller":"traceutil/trace.go:171","msg":"trace[92063890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"813.371936ms","start":"2026-05-09T12:56:05.686065Z","end":"2026-05-09T12:56:06.499437Z","steps":["trace[92063890] 'agreement among raft nodes before linearized reading' (duration: 813.325395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.49948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.686047Z","time spent":"813.425347ms","remote":"127.0.0.1:37932","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-05-09T12:56:06.499579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.785611ms","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-05-09T12:56:06.499608Z","caller":"traceutil/trace.go:171","msg":"trace[447052388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"599.841622ms","start":"2026-05-09T12:56:05.899758Z","end":"2026-05-09T12:56:06.499599Z","steps":["trace[447052388] 'agreement among raft nodes before linearized reading' (duration: 599.77153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.49964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.899743Z","time spent":"599.879773ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:56:06.499764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.002037ms","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-05-09T12:56:06.499371Z","caller":"traceutil/trace.go:171","msg":"trace[1774266673] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"608.886052ms","start":"2026-05-09T12:56:05.890474Z","end":"2026-05-09T12:56:06.499361Z","steps":["trace[1774266673] 'process raft request' (duration: 575.142192ms)","trace[1774266673] 'compare' (duration: 33.034121ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:06.499788Z","caller":"traceutil/trace.go:171","msg":"trace[777000213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"600.046308ms","start":"2026-05-09T12:56:05.899736Z","end":"2026-05-09T12:56:06.499782Z","steps":["trace[777000213] 'agreement among raft nodes before linearized reading' (duration: 599.988637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.499807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.899725Z","time spent":"600.078719ms","remote":"127.0.0.1:38218","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-05-09T12:56:06.499854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.890448Z","time spent":"609.351225ms","remote":"127.0.0.1:38218","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-05-09T12:56:06.500001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.309528ms","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-05-09T12:56:06.500034Z","caller":"traceutil/trace.go:171","msg":"trace[1645819781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"602.378969ms","start":"2026-05-09T12:56:05.897646Z","end":"2026-05-09T12:56:06.500024Z","steps":["trace[1645819781] 'agreement among raft nodes before linearized reading' (duration: 602.293957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.500066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.897621Z","time spent":"602.439121ms","remote":"127.0.0.1:38218","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-05-09T12:56:06.499331Z","caller":"traceutil/trace.go:171","msg":"trace[958650630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"597.313816ms","start":"2026-05-09T12:56:05.901988Z","end":"2026-05-09T12:56:06.499301Z","steps":["trace[958650630] 'agreement among raft nodes before linearized reading' (duration: 597.138172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:06.500138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:05.90198Z","time spent":"598.149798ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T12:56:07.306435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.853354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:56:07.306919Z","caller":"traceutil/trace.go:171","msg":"trace[514696320] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"511.394669ms","start":"2026-05-09T12:56:06.795501Z","end":"2026-05-09T12:56:07.306895Z","steps":["trace[514696320] 'process raft request' (duration: 511.346578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:07.307016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.795477Z","time spent":"511.497063ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:07.307267Z","caller":"traceutil/trace.go:171","msg":"trace[550009563] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"791.141839ms","start":"2026-05-09T12:56:06.516111Z","end":"2026-05-09T12:56:07.307252Z","steps":["trace[550009563] 'process raft request' (duration: 113.34643ms)","trace[550009563] 'compare' (duration: 676.588537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:07.307369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.516092Z","time spent":"791.2103ms","remote":"127.0.0.1:38218","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-05-09T12:56:07.307608Z","caller":"traceutil/trace.go:171","msg":"trace[1186790277] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"788.854528ms","start":"2026-05-09T12:56:06.518726Z","end":"2026-05-09T12:56:07.307581Z","steps":["trace[1186790277] 'process raft request' (duration: 788.066537ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:07.307632Z","caller":"traceutil/trace.go:171","msg":"trace[120603784] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"790.596135ms","start":"2026-05-09T12:56:06.517006Z","end":"2026-05-09T12:56:07.307602Z","steps":["trace[120603784] 'process raft request' (duration: 789.697091ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:07.307646Z","caller":"traceutil/trace.go:171","msg":"trace[1238101014] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"790.550674ms","start":"2026-05-09T12:56:06.517068Z","end":"2026-05-09T12:56:07.307618Z","steps":["trace[1238101014] 'process raft request' (duration: 789.67636ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:07.307679Z","caller":"traceutil/trace.go:171","msg":"trace[66398971] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1253; }","duration":"680.019488ms","start":"2026-05-09T12:56:06.627645Z","end":"2026-05-09T12:56:07.307664Z","steps":["trace[66398971] 'read index received' (duration: 1.741626ms)","trace[66398971] 'applied index is now lower than readState.Index' (duration: 678.276182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:07.307748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.518707Z","time spent":"788.960601ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:07.307761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.114871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:56:07.307771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.516994Z","time spent":"790.697678ms","remote":"127.0.0.1:38218","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-05-09T12:56:07.307796Z","caller":"traceutil/trace.go:171","msg":"trace[1478307834] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1209; }","duration":"680.190683ms","start":"2026-05-09T12:56:06.627591Z","end":"2026-05-09T12:56:07.307782Z","steps":["trace[1478307834] 'agreement among raft nodes before linearized reading' (duration: 680.122101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:07.307799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.517061Z","time spent":"790.655306ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:07.307839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.62757Z","time spent":"680.255744ms","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-09T12:56:07.307925Z","caller":"traceutil/trace.go:171","msg":"trace[296032892] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"791.57508ms","start":"2026-05-09T12:56:06.516338Z","end":"2026-05-09T12:56:07.307913Z","steps":["trace[296032892] 'process raft request' (duration: 790.233095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:07.307979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:06.516332Z","time spent":"791.618251ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:19.06466Z","caller":"traceutil/trace.go:171","msg":"trace[216892489] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"371.606443ms","start":"2026-05-09T12:56:18.693025Z","end":"2026-05-09T12:56:19.064631Z","steps":["trace[216892489] 'read index received' (duration: 371.345916ms)","trace[216892489] 'applied index is now lower than readState.Index' (duration: 259.377µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:19.064717Z","caller":"traceutil/trace.go:171","msg":"trace[760264242] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"459.372467ms","start":"2026-05-09T12:56:18.605306Z","end":"2026-05-09T12:56:19.064679Z","steps":["trace[760264242] 'process raft request' (duration: 459.173552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:19.064812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.760806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:19.064849Z","caller":"traceutil/trace.go:171","msg":"trace[1114809230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"371.839449ms","start":"2026-05-09T12:56:18.692998Z","end":"2026-05-09T12:56:19.064837Z","steps":["trace[1114809230] 'agreement among raft nodes before linearized reading' (duration: 371.761837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:19.064889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:18.692986Z","time spent":"371.89643ms","remote":"127.0.0.1:37932","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-05-09T12:56:19.064903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:18.605281Z","time spent":"459.532981ms","remote":"127.0.0.1:38218","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-05-09T12:56:28.652794Z","caller":"traceutil/trace.go:171","msg":"trace[15861276] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"155.552323ms","start":"2026-05-09T12:56:28.497224Z","end":"2026-05-09T12:56:28.652776Z","steps":["trace[15861276] 'process raft request' (duration: 155.438311ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:28.697206Z","caller":"traceutil/trace.go:171","msg":"trace[991423729] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"170.058765ms","start":"2026-05-09T12:56:28.527126Z","end":"2026-05-09T12:56:28.697184Z","steps":["trace[991423729] 'process raft request' (duration: 170.014594ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:28.697224Z","caller":"traceutil/trace.go:171","msg":"trace[373318094] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"198.62337ms","start":"2026-05-09T12:56:28.498578Z","end":"2026-05-09T12:56:28.697202Z","steps":["trace[373318094] 'process raft request' (duration: 198.442635ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:28.808347Z","caller":"traceutil/trace.go:171","msg":"trace[1830110055] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"106.743086ms","start":"2026-05-09T12:56:28.701567Z","end":"2026-05-09T12:56:28.80831Z","steps":["trace[1830110055] 'process raft request' (duration: 79.623301ms)","trace[1830110055] 'compare' (duration: 26.841587ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:33.467274Z","caller":"traceutil/trace.go:171","msg":"trace[567410360] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"399.18433ms","start":"2026-05-09T12:56:33.068057Z","end":"2026-05-09T12:56:33.467242Z","steps":["trace[567410360] 'process raft request' (duration: 398.960365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.46752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.067969Z","time spent":"399.447207ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:33.747826Z","caller":"traceutil/trace.go:171","msg":"trace[998211192] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1387; }","duration":"336.416403ms","start":"2026-05-09T12:56:33.411388Z","end":"2026-05-09T12:56:33.747804Z","steps":["trace[998211192] 'read index received' (duration: 55.713789ms)","trace[998211192] 'applied index is now lower than readState.Index' (duration: 280.701184ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:33.747923Z","caller":"traceutil/trace.go:171","msg":"trace[1518536316] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"558.926693ms","start":"2026-05-09T12:56:33.188963Z","end":"2026-05-09T12:56:33.74789Z","steps":["trace[1518536316] 'process raft request' (duration: 558.668907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.713508ms","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-05-09T12:56:33.748075Z","caller":"traceutil/trace.go:171","msg":"trace[1067375650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1334; }","duration":"315.802391ms","start":"2026-05-09T12:56:33.432262Z","end":"2026-05-09T12:56:33.748064Z","steps":["trace[1067375650] 'agreement among raft nodes before linearized reading' (duration: 315.666257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.68166ms","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-05-09T12:56:33.748121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.188935Z","time spent":"559.089238ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:33.748157Z","caller":"traceutil/trace.go:171","msg":"trace[2087827343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"336.769484ms","start":"2026-05-09T12:56:33.411371Z","end":"2026-05-09T12:56:33.748141Z","steps":["trace[2087827343] 'agreement among raft nodes before linearized reading' (duration: 336.553727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.790863ms","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-05-09T12:56:33.748211Z","caller":"traceutil/trace.go:171","msg":"trace[358075952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"309.842313ms","start":"2026-05-09T12:56:33.438363Z","end":"2026-05-09T12:56:33.748205Z","steps":["trace[358075952] 'agreement among raft nodes before linearized reading' (duration: 309.759251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.438345Z","time spent":"309.885275ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:56:33.748202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.411281Z","time spent":"336.911288ms","remote":"127.0.0.1:38218","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-05-09T12:56:33.748104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.432239Z","time spent":"315.857492ms","remote":"127.0.0.1:38218","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-05-09T12:56:33.74835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.954395ms","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-05-09T12:56:33.748398Z","caller":"traceutil/trace.go:171","msg":"trace[1418560549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"313.025747ms","start":"2026-05-09T12:56:33.43536Z","end":"2026-05-09T12:56:33.748385Z","steps":["trace[1418560549] 'agreement among raft nodes before linearized reading' (duration: 312.924084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.116676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-09T12:56:33.748438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.435344Z","time spent":"313.087149ms","remote":"127.0.0.1:38218","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-05-09T12:56:33.748461Z","caller":"traceutil/trace.go:171","msg":"trace[1783777880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"314.232949ms","start":"2026-05-09T12:56:33.434209Z","end":"2026-05-09T12:56:33.748442Z","steps":["trace[1783777880] 'agreement among raft nodes before linearized reading' (duration: 314.057535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:33.748516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:33.434192Z","time spent":"314.311361ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T12:56:36.80021Z","caller":"traceutil/trace.go:171","msg":"trace[1910701177] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"113.911075ms","start":"2026-05-09T12:56:36.686277Z","end":"2026-05-09T12:56:36.800188Z","steps":["trace[1910701177] 'read index received' (duration: 113.750971ms)","trace[1910701177] 'applied index is now lower than readState.Index' (duration: 159.024µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:36.800327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.038278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:36.800363Z","caller":"traceutil/trace.go:171","msg":"trace[922376470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"114.12646ms","start":"2026-05-09T12:56:36.686226Z","end":"2026-05-09T12:56:36.800352Z","steps":["trace[922376470] 'agreement among raft nodes before linearized reading' (duration: 114.053928ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:36.800484Z","caller":"traceutil/trace.go:171","msg":"trace[1235909506] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"230.476339ms","start":"2026-05-09T12:56:36.56998Z","end":"2026-05-09T12:56:36.800457Z","steps":["trace[1235909506] 'process raft request' (duration: 230.050888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:12.339937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.904328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:12.340237Z","caller":"traceutil/trace.go:171","msg":"trace[1772168440] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"245.814675ms","start":"2026-05-09T12:57:12.094392Z","end":"2026-05-09T12:57:12.340206Z","steps":["trace[1772168440] 'process raft request' (duration: 110.548476ms)","trace[1772168440] 'compare' (duration: 134.785546ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:12.340439Z","caller":"traceutil/trace.go:171","msg":"trace[323960896] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"244.638644ms","start":"2026-05-09T12:57:12.095778Z","end":"2026-05-09T12:57:12.340417Z","steps":["trace[323960896] 'process raft request' (duration: 244.268784ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:12.340479Z","caller":"traceutil/trace.go:171","msg":"trace[238362533] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1574; }","duration":"246.060951ms","start":"2026-05-09T12:57:12.094404Z","end":"2026-05-09T12:57:12.340465Z","steps":["trace[238362533] 'read index received' (duration: 41.334677ms)","trace[238362533] 'applied index is now lower than readState.Index' (duration: 204.725264ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:12.340539Z","caller":"traceutil/trace.go:171","msg":"trace[1923788485] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"243.943695ms","start":"2026-05-09T12:57:12.096533Z","end":"2026-05-09T12:57:12.340477Z","steps":["trace[1923788485] 'process raft request' (duration: 243.752541ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:12.340586Z","caller":"traceutil/trace.go:171","msg":"trace[1883311764] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"243.647568ms","start":"2026-05-09T12:57:12.096928Z","end":"2026-05-09T12:57:12.340576Z","steps":["trace[1883311764] 'process raft request' (duration: 243.410562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:12.341801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.378675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:57:12.341851Z","caller":"traceutil/trace.go:171","msg":"trace[1894495397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1516; }","duration":"247.476799ms","start":"2026-05-09T12:57:12.094362Z","end":"2026-05-09T12:57:12.341838Z","steps":["trace[1894495397] 'agreement among raft nodes before linearized reading' (duration: 246.192225ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:12.485298Z","caller":"traceutil/trace.go:171","msg":"trace[313036044] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"139.365905ms","start":"2026-05-09T12:57:12.345901Z","end":"2026-05-09T12:57:12.485267Z","steps":["trace[313036044] 'process raft request' (duration: 99.415015ms)","trace[313036044] 'compare' (duration: 39.844888ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:20.570725Z","caller":"traceutil/trace.go:171","msg":"trace[993904785] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"101.930641ms","start":"2026-05-09T12:57:20.468775Z","end":"2026-05-09T12:57:20.570706Z","steps":["trace[993904785] 'process raft request' (duration: 101.032858ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:20.570756Z","caller":"traceutil/trace.go:171","msg":"trace[126501188] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"102.340991ms","start":"2026-05-09T12:57:20.468384Z","end":"2026-05-09T12:57:20.570725Z","steps":["trace[126501188] 'process raft request' (duration: 101.347806ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:20.570892Z","caller":"traceutil/trace.go:171","msg":"trace[1894299490] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"102.468554ms","start":"2026-05-09T12:57:20.468417Z","end":"2026-05-09T12:57:20.570886Z","steps":["trace[1894299490] 'process raft request' (duration: 101.358916ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:20.572815Z","caller":"traceutil/trace.go:171","msg":"trace[1079627182] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"101.889219ms","start":"2026-05-09T12:57:20.4683Z","end":"2026-05-09T12:57:20.570189Z","steps":["trace[1079627182] 'process raft request' (duration: 20.989752ms)","trace[1079627182] 'compare' (duration: 79.962803ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:32.76228Z","caller":"traceutil/trace.go:171","msg":"trace[1462141860] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"101.849989ms","start":"2026-05-09T12:57:32.660403Z","end":"2026-05-09T12:57:32.762253Z","steps":["trace[1462141860] 'process raft request' (duration: 101.671604ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:32.895131Z","caller":"traceutil/trace.go:171","msg":"trace[604128672] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"127.988596ms","start":"2026-05-09T12:57:32.767111Z","end":"2026-05-09T12:57:32.8951Z","steps":["trace[604128672] 'process raft request' (duration: 123.338773ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:32.895161Z","caller":"traceutil/trace.go:171","msg":"trace[880447635] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"127.590505ms","start":"2026-05-09T12:57:32.767541Z","end":"2026-05-09T12:57:32.895131Z","steps":["trace[880447635] 'process raft request' (duration: 127.534104ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:32.895381Z","caller":"traceutil/trace.go:171","msg":"trace[314478613] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"128.229682ms","start":"2026-05-09T12:57:32.767134Z","end":"2026-05-09T12:57:32.895364Z","steps":["trace[314478613] 'process raft request' (duration: 127.836162ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:54.473411Z","caller":"traceutil/trace.go:171","msg":"trace[195476863] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"109.870271ms","start":"2026-05-09T12:58:54.363514Z","end":"2026-05-09T12:58:54.473384Z","steps":["trace[195476863] 'process raft request' (duration: 109.195415ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:05.355488Z","caller":"traceutil/trace.go:171","msg":"trace[585637693] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"108.364513ms","start":"2026-05-09T12:59:05.247092Z","end":"2026-05-09T12:59:05.355456Z","steps":["trace[585637693] 'process raft request' (duration: 108.116126ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:05.488805Z","caller":"traceutil/trace.go:171","msg":"trace[523458361] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"128.402399ms","start":"2026-05-09T12:59:05.360362Z","end":"2026-05-09T12:59:05.488765Z","steps":["trace[523458361] 'process raft request' (duration: 115.704295ms)","trace[523458361] 'compare' (duration: 12.57449ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:05.820066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.793382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:59:05.820451Z","caller":"traceutil/trace.go:171","msg":"trace[1448170394] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"238.352312ms","start":"2026-05-09T12:59:05.582074Z","end":"2026-05-09T12:59:05.820426Z","steps":["trace[1448170394] 'process raft request' (duration: 81.100208ms)","trace[1448170394] 'compare' (duration: 156.633417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:05.820691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.428695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:59:05.820806Z","caller":"traceutil/trace.go:171","msg":"trace[2012967370] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2034; }","duration":"145.62529ms","start":"2026-05-09T12:59:05.675158Z","end":"2026-05-09T12:59:05.820783Z","steps":["trace[2012967370] 'agreement among raft nodes before linearized reading' (duration: 145.418375ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:05.820393Z","caller":"traceutil/trace.go:171","msg":"trace[1920724547] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2119; }","duration":"145.157997ms","start":"2026-05-09T12:59:05.675214Z","end":"2026-05-09T12:59:05.820372Z","steps":["trace[1920724547] 'read index received' (duration: 59.802µs)","trace[1920724547] 'applied index is now lower than readState.Index' (duration: 145.096495ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:59:26.767924Z","caller":"traceutil/trace.go:171","msg":"trace[300969019] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"114.899585ms","start":"2026-05-09T12:59:26.653004Z","end":"2026-05-09T12:59:26.767903Z","steps":["trace[300969019] 'process raft request' (duration: 114.777322ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:33.870622Z","caller":"traceutil/trace.go:171","msg":"trace[145839322] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2279; }","duration":"184.654711ms","start":"2026-05-09T12:59:33.685944Z","end":"2026-05-09T12:59:33.870599Z","steps":["trace[145839322] 'read index received' (duration: 184.281921ms)","trace[145839322] 'applied index is now lower than readState.Index' (duration: 371.63µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:59:33.870751Z","caller":"traceutil/trace.go:171","msg":"trace[1332333963] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"377.66819ms","start":"2026-05-09T12:59:33.493059Z","end":"2026-05-09T12:59:33.870727Z","steps":["trace[1332333963] 'process raft request' (duration: 377.400373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:33.870806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.820235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:59:33.870886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:59:33.493027Z","time spent":"377.776043ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:59:33.871024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.419794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T12:59:33.871061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.672897ms","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-05-09T12:59:33.871084Z","caller":"traceutil/trace.go:171","msg":"trace[1992005422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2188; }","duration":"132.525127ms","start":"2026-05-09T12:59:33.738544Z","end":"2026-05-09T12:59:33.871069Z","steps":["trace[1992005422] 'agreement among raft nodes before linearized reading' (duration: 132.371363ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:33.871119Z","caller":"traceutil/trace.go:171","msg":"trace[355440365] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2188; }","duration":"107.75634ms","start":"2026-05-09T12:59:33.763346Z","end":"2026-05-09T12:59:33.871103Z","steps":["trace[355440365] 'agreement among raft nodes before linearized reading' (duration: 107.581455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:33.871379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.820764ms","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-05-09T12:59:33.871395Z","caller":"traceutil/trace.go:171","msg":"trace[1269492111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"184.936788ms","start":"2026-05-09T12:59:33.685902Z","end":"2026-05-09T12:59:33.870839Z","steps":["trace[1269492111] 'agreement among raft nodes before linearized reading' (duration: 184.829865ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:33.871432Z","caller":"traceutil/trace.go:171","msg":"trace[178448002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2188; }","duration":"114.966427ms","start":"2026-05-09T12:59:33.75645Z","end":"2026-05-09T12:59:33.871416Z","steps":["trace[178448002] 'agreement among raft nodes before linearized reading' (duration: 114.769432ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:44.347095Z","caller":"traceutil/trace.go:171","msg":"trace[1806216969] linearizableReadLoop","detail":"{readStateIndex:2339; appliedIndex:2338; }","duration":"115.416129ms","start":"2026-05-09T12:59:44.231656Z","end":"2026-05-09T12:59:44.347072Z","steps":["trace[1806216969] 'read index received' (duration: 115.342127ms)","trace[1806216969] 'applied index is now lower than readState.Index' (duration: 72.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:59:44.347221Z","caller":"traceutil/trace.go:171","msg":"trace[478898849] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"135.432932ms","start":"2026-05-09T12:59:44.211762Z","end":"2026-05-09T12:59:44.347195Z","steps":["trace[478898849] 'process raft request' (duration: 135.126785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:44.347349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.680956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-09T12:59:44.347412Z","caller":"traceutil/trace.go:171","msg":"trace[209980522] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:2244; }","duration":"115.796078ms","start":"2026-05-09T12:59:44.231603Z","end":"2026-05-09T12:59:44.347399Z","steps":["trace[209980522] 'agreement among raft nodes before linearized reading' (duration: 115.598363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:44.353127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.783966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-05-09T12:59:44.353222Z","caller":"traceutil/trace.go:171","msg":"trace[772235333] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2245; }","duration":"108.91437ms","start":"2026-05-09T12:59:44.24428Z","end":"2026-05-09T12:59:44.353194Z","steps":["trace[772235333] 'agreement among raft nodes before linearized reading' (duration: 108.736524ms)"],"step_count":1}