{"level":"warn","ts":"2026-04-17T04:27:08.561716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T04:27:08.561833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T04:27:08.561905Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T04:27:08.561918Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-17T04:27:08.561938Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T04:27:08.562561Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-17T04:27:08.562636Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T04:27:08.566966Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.14099ms"} {"level":"info","ts":"2026-04-17T04:27:08.579164Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-17T04:27:08.579272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T04:27:08.579304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-17T04:27:08.579315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T04:27:08.579333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-17T04:27:08.579378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-17T04:27:08.585359Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T04:27:08.588454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T04:27:08.589766Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T04:27:08.592486Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T04:27:08.59318Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T04:27:08.593263Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T04:27:08.595282Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T04:27:08.595306Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T04:27:08.595514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-17T04:27:08.595995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-17T04:27:08.598873Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T04:27:08.59899Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-17T04:27:08.599031Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-17T04:27:08.599244Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T04:27:08.599304Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T04:27:09.180191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T04:27:09.180278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T04:27:09.180327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-17T04:27:09.180347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-17T04:27:09.180357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-17T04:27:09.180376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-17T04:27:09.180389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-17T04:27:09.18164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:27:09.182275Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T04:27:09.182285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:27:09.182273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:27:09.182679Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T04:27:09.182758Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T04:27:09.183269Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:27:09.183505Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:27:09.183662Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:27:09.186003Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-17T04:27:09.186957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-17T04:27:29.140027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.936164ms","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-04-17T04:27:29.140132Z","caller":"traceutil/trace.go:171","msg":"trace[1172484540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"143.134996ms","start":"2026-04-17T04:27:28.996974Z","end":"2026-04-17T04:27:29.140109Z","steps":["trace[1172484540] 'range keys from in-memory index tree' (duration: 142.696292ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:27:29.283586Z","caller":"traceutil/trace.go:171","msg":"trace[1895643341] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"118.352716ms","start":"2026-04-17T04:27:29.165215Z","end":"2026-04-17T04:27:29.283568Z","steps":["trace[1895643341] 'read index received' (duration: 118.237045ms)","trace[1895643341] 'applied index is now lower than readState.Index' (duration: 115.221µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:27:29.283619Z","caller":"traceutil/trace.go:171","msg":"trace[340486773] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"139.26358ms","start":"2026-04-17T04:27:29.144336Z","end":"2026-04-17T04:27:29.283599Z","steps":["trace[340486773] 'process raft request' (duration: 139.127579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:27:29.283679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.460607ms","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-04-17T04:27:29.283706Z","caller":"traceutil/trace.go:171","msg":"trace[898126928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"118.554648ms","start":"2026-04-17T04:27:29.165141Z","end":"2026-04-17T04:27:29.283695Z","steps":["trace[898126928] 'agreement among raft nodes before linearized reading' (duration: 118.497867ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:27:29.387179Z","caller":"traceutil/trace.go:171","msg":"trace[180089580] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"100.186298ms","start":"2026-04-17T04:27:29.28674Z","end":"2026-04-17T04:27:29.386926Z","steps":["trace[180089580] 'process raft request' (duration: 94.399155ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:27:41.486951Z","caller":"traceutil/trace.go:171","msg":"trace[1277216882] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"119.718791ms","start":"2026-04-17T04:27:41.36716Z","end":"2026-04-17T04:27:41.486879Z","steps":["trace[1277216882] 'process raft request' (duration: 85.688472ms)","trace[1277216882] 'compare' (duration: 33.664524ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:27:41.658817Z","caller":"traceutil/trace.go:171","msg":"trace[1923605815] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"197.432123ms","start":"2026-04-17T04:27:41.461343Z","end":"2026-04-17T04:27:41.658775Z","steps":["trace[1923605815] 'process raft request' (duration: 156.662764ms)","trace[1923605815] 'compare' (duration: 40.277585ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:27:41.659001Z","caller":"traceutil/trace.go:171","msg":"trace[1907422086] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"167.149779ms","start":"2026-04-17T04:27:41.491816Z","end":"2026-04-17T04:27:41.658966Z","steps":["trace[1907422086] 'process raft request' (duration: 166.887326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:27:42.035996Z","caller":"traceutil/trace.go:171","msg":"trace[1098387909] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"111.390826ms","start":"2026-04-17T04:27:41.924575Z","end":"2026-04-17T04:27:42.035966Z","steps":["trace[1098387909] 'read index received' (duration: 111.179554ms)","trace[1098387909] 'applied index is now lower than readState.Index' (duration: 209.852µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:27:42.03627Z","caller":"traceutil/trace.go:171","msg":"trace[1925103568] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"310.426922ms","start":"2026-04-17T04:27:41.725812Z","end":"2026-04-17T04:27:42.036239Z","steps":["trace[1925103568] 'process raft request' (duration: 309.978148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:27:42.036298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.710188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T04:27:42.036817Z","caller":"traceutil/trace.go:171","msg":"trace[180316288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"112.232213ms","start":"2026-04-17T04:27:41.924516Z","end":"2026-04-17T04:27:42.036748Z","steps":["trace[180316288] 'agreement among raft nodes before linearized reading' (duration: 111.565207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:27:42.036854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:27:41.725783Z","time spent":"310.606364ms","remote":"127.0.0.1:53528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:27:42.174462Z","caller":"traceutil/trace.go:171","msg":"trace[625922889] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"117.63533ms","start":"2026-04-17T04:27:42.056799Z","end":"2026-04-17T04:27:42.174434Z","steps":["trace[625922889] 'process raft request' (duration: 113.24477ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:29:16.643843Z","caller":"traceutil/trace.go:171","msg":"trace[970860160] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"102.476023ms","start":"2026-04-17T04:29:16.541327Z","end":"2026-04-17T04:29:16.643803Z","steps":["trace[970860160] 'process raft request' (duration: 95.864401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:29:16.97642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.623583ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:29:16.976616Z","caller":"traceutil/trace.go:171","msg":"trace[916376956] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1143; }","duration":"115.813126ms","start":"2026-04-17T04:29:16.860746Z","end":"2026-04-17T04:29:16.97656Z","steps":["trace[916376956] 'range keys from in-memory index tree' (duration: 115.599963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:29:16.976809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.749835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:29:16.976945Z","caller":"traceutil/trace.go:171","msg":"trace[1868835572] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"189.963114ms","start":"2026-04-17T04:29:16.786965Z","end":"2026-04-17T04:29:16.976928Z","steps":["trace[1868835572] 'process raft request' (duration: 20.685883ms)","trace[1868835572] 'compare' (duration: 168.641563ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:29:35.985922Z","caller":"traceutil/trace.go:171","msg":"trace[678848917] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"128.512712ms","start":"2026-04-17T04:29:35.857358Z","end":"2026-04-17T04:29:35.985871Z","steps":["trace[678848917] 'process raft request' (duration: 128.358611ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:29:36.253956Z","caller":"traceutil/trace.go:171","msg":"trace[848198822] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"263.690476ms","start":"2026-04-17T04:29:35.990222Z","end":"2026-04-17T04:29:36.253913Z","steps":["trace[848198822] 'process raft request' (duration: 263.537494ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:29:37.208373Z","caller":"traceutil/trace.go:171","msg":"trace[731936316] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"150.891254ms","start":"2026-04-17T04:29:37.057445Z","end":"2026-04-17T04:29:37.208337Z","steps":["trace[731936316] 'process raft request' (duration: 90.191031ms)","trace[731936316] 'compare' (duration: 60.365879ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:29:38.333031Z","caller":"traceutil/trace.go:171","msg":"trace[1901035971] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"338.751764ms","start":"2026-04-17T04:29:37.994244Z","end":"2026-04-17T04:29:38.332996Z","steps":["trace[1901035971] 'process raft request' (duration: 338.522531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:29:38.333227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:29:37.994227Z","time spent":"338.913145ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:29:38.543631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.453438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:29:38.543847Z","caller":"traceutil/trace.go:171","msg":"trace[20302083] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1261; }","duration":"101.197748ms","start":"2026-04-17T04:29:38.442627Z","end":"2026-04-17T04:29:38.543825Z","steps":["trace[20302083] 'read index received' (duration: 78.556805ms)","trace[20302083] 'applied index is now lower than readState.Index' (duration: 22.639943ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:29:38.543904Z","caller":"traceutil/trace.go:171","msg":"trace[589336095] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"207.228428ms","start":"2026-04-17T04:29:38.336651Z","end":"2026-04-17T04:29:38.543879Z","steps":["trace[589336095] 'process raft request' (duration: 207.105376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:29:38.544097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.445772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:29:38.544121Z","caller":"traceutil/trace.go:171","msg":"trace[1080545193] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"207.647124ms","start":"2026-04-17T04:29:38.336263Z","end":"2026-04-17T04:29:38.54391Z","steps":["trace[1080545193] 'process raft request' (duration: 95.812601ms)","trace[1080545193] 'compare' (duration: 111.319857ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:29:38.544203Z","caller":"traceutil/trace.go:171","msg":"trace[26464031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"101.595304ms","start":"2026-04-17T04:29:38.442585Z","end":"2026-04-17T04:29:38.54418Z","steps":["trace[26464031] 'agreement among raft nodes before linearized reading' (duration: 101.310631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.651286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764772339790255,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T04:31:39.872615Z","caller":"traceutil/trace.go:171","msg":"trace[57278100] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"738.335392ms","start":"2026-04-17T04:31:39.134261Z","end":"2026-04-17T04:31:39.872596Z","steps":["trace[57278100] 'process raft request' (duration: 738.19681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.873578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:31:39.134244Z","time spent":"739.2205ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:31:39.923342Z","caller":"traceutil/trace.go:171","msg":"trace[1193863650] linearizableReadLoop","detail":"{readStateIndex:1681; appliedIndex:1679; }","duration":"772.257463ms","start":"2026-04-17T04:31:39.151053Z","end":"2026-04-17T04:31:39.92331Z","steps":["trace[1193863650] 'read index received' (duration: 721.428699ms)","trace[1193863650] 'applied index is now lower than readState.Index' (duration: 50.827654ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:31:39.923434Z","caller":"traceutil/trace.go:171","msg":"trace[746853088] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"677.538551ms","start":"2026-04-17T04:31:39.245849Z","end":"2026-04-17T04:31:39.923388Z","steps":["trace[746853088] 'process raft request' (duration: 676.96162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.923623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:31:39.245807Z","time spent":"677.719955ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:31:39.923796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.713832ms","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-04-17T04:31:39.923842Z","caller":"traceutil/trace.go:171","msg":"trace[1911848708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1609; }","duration":"772.802934ms","start":"2026-04-17T04:31:39.151027Z","end":"2026-04-17T04:31:39.92383Z","steps":["trace[1911848708] 'agreement among raft nodes before linearized reading' (duration: 772.451547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.923882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:31:39.151016Z","time spent":"772.854314ms","remote":"127.0.0.1:53550","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-04-17T04:31:39.923938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.785433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:31:39.923975Z","caller":"traceutil/trace.go:171","msg":"trace[414190508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1609; }","duration":"598.886645ms","start":"2026-04-17T04:31:39.32508Z","end":"2026-04-17T04:31:39.923966Z","steps":["trace[414190508] 'agreement among raft nodes before linearized reading' (duration: 598.727022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.923938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.271333ms","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-04-17T04:31:39.924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:31:39.325067Z","time spent":"598.929087ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T04:31:39.924032Z","caller":"traceutil/trace.go:171","msg":"trace[1814109199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1609; }","duration":"772.407155ms","start":"2026-04-17T04:31:39.151602Z","end":"2026-04-17T04:31:39.924009Z","steps":["trace[1814109199] 'agreement among raft nodes before linearized reading' (duration: 772.082649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:31:39.924086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:31:39.151594Z","time spent":"772.482276ms","remote":"127.0.0.1:53550","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-04-17T04:34:19.222197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.06789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T04:34:19.222291Z","caller":"traceutil/trace.go:171","msg":"trace[1288572381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2134; }","duration":"108.212822ms","start":"2026-04-17T04:34:19.11406Z","end":"2026-04-17T04:34:19.222273Z","steps":["trace[1288572381] 'range keys from in-memory index tree' (duration: 107.892836ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:27.479361Z","caller":"traceutil/trace.go:171","msg":"trace[2073133329] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"197.957592ms","start":"2026-04-17T04:34:27.281381Z","end":"2026-04-17T04:34:27.479339Z","steps":["trace[2073133329] 'process raft request' (duration: 197.821499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:29.138807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.994554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:34:29.138954Z","caller":"traceutil/trace.go:171","msg":"trace[2080289108] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"220.17356ms","start":"2026-04-17T04:34:28.918762Z","end":"2026-04-17T04:34:29.138935Z","steps":["trace[2080289108] 'process raft request' (duration: 109.86003ms)","trace[2080289108] 'compare' (duration: 109.747379ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:34:32.507542Z","caller":"traceutil/trace.go:171","msg":"trace[73438565] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"107.01332ms","start":"2026-04-17T04:34:32.400499Z","end":"2026-04-17T04:34:32.507512Z","steps":["trace[73438565] 'process raft request' (duration: 41.635856ms)","trace[73438565] 'compare' (duration: 65.238871ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:34:33.32889Z","caller":"traceutil/trace.go:171","msg":"trace[1209244548] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"115.678242ms","start":"2026-04-17T04:34:33.213191Z","end":"2026-04-17T04:34:33.32887Z","steps":["trace[1209244548] 'process raft request' (duration: 115.537589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:33.674631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.018599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:34:33.674717Z","caller":"traceutil/trace.go:171","msg":"trace[594024276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2182; }","duration":"231.148631ms","start":"2026-04-17T04:34:33.443549Z","end":"2026-04-17T04:34:33.674698Z","steps":["trace[594024276] 'range keys from in-memory index tree' (duration: 230.942097ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:33.674718Z","caller":"traceutil/trace.go:171","msg":"trace[899319875] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"113.428776ms","start":"2026-04-17T04:34:33.561275Z","end":"2026-04-17T04:34:33.674704Z","steps":["trace[899319875] 'read index received' (duration: 109.506383ms)","trace[899319875] 'applied index is now lower than readState.Index' (duration: 3.921513ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:34:33.674773Z","caller":"traceutil/trace.go:171","msg":"trace[2053657026] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"197.654857ms","start":"2026-04-17T04:34:33.477084Z","end":"2026-04-17T04:34:33.674739Z","steps":["trace[2053657026] 'process raft request' (duration: 193.749745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:33.674859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.581019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:34:33.674911Z","caller":"traceutil/trace.go:171","msg":"trace[664204584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2183; }","duration":"113.675411ms","start":"2026-04-17T04:34:33.561223Z","end":"2026-04-17T04:34:33.674898Z","steps":["trace[664204584] 'agreement among raft nodes before linearized reading' (duration: 113.523918ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:33.814691Z","caller":"traceutil/trace.go:171","msg":"trace[1736387818] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"133.749253ms","start":"2026-04-17T04:34:33.680907Z","end":"2026-04-17T04:34:33.814656Z","steps":["trace[1736387818] 'process raft request' (duration: 94.992308ms)","trace[1736387818] 'compare' (duration: 38.614062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:34:37.919116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.864238ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-17T04:34:43.408434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.732288ms","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-04-17T04:34:43.408529Z","caller":"traceutil/trace.go:171","msg":"trace[762726841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2213; }","duration":"148.883321ms","start":"2026-04-17T04:34:43.259625Z","end":"2026-04-17T04:34:43.408509Z","steps":["trace[762726841] 'range keys from in-memory index tree' (duration: 148.492143ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:44.170144Z","caller":"traceutil/trace.go:171","msg":"trace[2039532400] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"157.923731ms","start":"2026-04-17T04:34:44.012199Z","end":"2026-04-17T04:34:44.170123Z","steps":["trace[2039532400] 'process raft request' (duration: 157.779308ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:49.5392Z","caller":"traceutil/trace.go:171","msg":"trace[15748611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2232; }","duration":"100.008313ms","start":"2026-04-17T04:34:49.439171Z","end":"2026-04-17T04:34:49.539179Z","steps":["trace[15748611] 'range keys from in-memory index tree' (duration: 99.85582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:49.539274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.455244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:34:49.539397Z","caller":"traceutil/trace.go:171","msg":"trace[1078793916] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2232; }","duration":"159.652407ms","start":"2026-04-17T04:34:49.379717Z","end":"2026-04-17T04:34:49.53937Z","steps":["trace[1078793916] 'count revisions from in-memory index tree' (duration: 159.380462ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:49.658498Z","caller":"traceutil/trace.go:171","msg":"trace[45849723] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"116.368788ms","start":"2026-04-17T04:34:49.542097Z","end":"2026-04-17T04:34:49.658466Z","steps":["trace[45849723] 'process raft request' (duration: 116.119722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:49.875934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.635456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:34:49.876068Z","caller":"traceutil/trace.go:171","msg":"trace[163808955] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"207.643707ms","start":"2026-04-17T04:34:49.668404Z","end":"2026-04-17T04:34:49.876048Z","steps":["trace[163808955] 'process raft request' (duration: 76.809546ms)","trace[163808955] 'compare' (duration: 130.494584ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:34:50.373329Z","caller":"traceutil/trace.go:171","msg":"trace[884605070] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"120.984404ms","start":"2026-04-17T04:34:50.252326Z","end":"2026-04-17T04:34:50.37331Z","steps":["trace[884605070] 'process raft request' (duration: 120.846851ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:51.152796Z","caller":"traceutil/trace.go:171","msg":"trace[1301625712] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"206.84353ms","start":"2026-04-17T04:34:50.945928Z","end":"2026-04-17T04:34:51.152771Z","steps":["trace[1301625712] 'process raft request' (duration: 206.616585ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:51.475691Z","caller":"traceutil/trace.go:171","msg":"trace[914215980] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"202.061919ms","start":"2026-04-17T04:34:51.273601Z","end":"2026-04-17T04:34:51.475663Z","steps":["trace[914215980] 'read index received' (duration: 200.790653ms)","trace[914215980] 'applied index is now lower than readState.Index' (duration: 1.269936ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:34:51.475809Z","caller":"traceutil/trace.go:171","msg":"trace[1514925868] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"317.784892ms","start":"2026-04-17T04:34:51.157974Z","end":"2026-04-17T04:34:51.475758Z","steps":["trace[1514925868] 'process raft request' (duration: 316.568917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:34:51.476012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.406536ms","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-04-17T04:34:51.476046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:34:51.157954Z","time spent":"317.958765ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:34:51.476067Z","caller":"traceutil/trace.go:171","msg":"trace[2090026455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2238; }","duration":"202.525218ms","start":"2026-04-17T04:34:51.273526Z","end":"2026-04-17T04:34:51.476051Z","steps":["trace[2090026455] 'agreement among raft nodes before linearized reading' (duration: 202.297773ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:53.885865Z","caller":"traceutil/trace.go:171","msg":"trace[1518732141] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"102.382694ms","start":"2026-04-17T04:34:53.783463Z","end":"2026-04-17T04:34:53.885846Z","steps":["trace[1518732141] 'process raft request' (duration: 102.226181ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:34:54.532807Z","caller":"traceutil/trace.go:171","msg":"trace[604890629] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"118.667526ms","start":"2026-04-17T04:34:54.41411Z","end":"2026-04-17T04:34:54.532777Z","steps":["trace[604890629] 'process raft request' (duration: 118.479291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:07.902059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.529609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T04:35:07.902177Z","caller":"traceutil/trace.go:171","msg":"trace[1061022756] linearizableReadLoop","detail":"{readStateIndex:2406; appliedIndex:2405; }","duration":"247.687798ms","start":"2026-04-17T04:35:07.654471Z","end":"2026-04-17T04:35:07.902159Z","steps":["trace[1061022756] 'read index received' (duration: 64.057016ms)","trace[1061022756] 'applied index is now lower than readState.Index' (duration: 183.629212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:07.902359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.867121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:07.902454Z","caller":"traceutil/trace.go:171","msg":"trace[2109151195] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2291; }","duration":"248.026715ms","start":"2026-04-17T04:35:07.654405Z","end":"2026-04-17T04:35:07.902432Z","steps":["trace[2109151195] 'agreement among raft nodes before linearized reading' (duration: 247.855041ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:21.897579Z","caller":"traceutil/trace.go:171","msg":"trace[1569507026] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"167.186136ms","start":"2026-04-17T04:35:21.730354Z","end":"2026-04-17T04:35:21.89754Z","steps":["trace[1569507026] 'process raft request' (duration: 166.941531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:22.108986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.161368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:22.109113Z","caller":"traceutil/trace.go:171","msg":"trace[1167796336] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2338; }","duration":"170.333921ms","start":"2026-04-17T04:35:21.938736Z","end":"2026-04-17T04:35:22.10907Z","steps":["trace[1167796336] 'count revisions from in-memory index tree' (duration: 170.031004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:22.644614Z","caller":"traceutil/trace.go:171","msg":"trace[1555598551] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"167.838099ms","start":"2026-04-17T04:35:22.476753Z","end":"2026-04-17T04:35:22.644592Z","steps":["trace[1555598551] 'process raft request' (duration: 167.689426ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:24.356069Z","caller":"traceutil/trace.go:171","msg":"trace[1107622384] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"148.282989ms","start":"2026-04-17T04:35:24.207763Z","end":"2026-04-17T04:35:24.356046Z","steps":["trace[1107622384] 'process raft request' (duration: 147.951221ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:24.575223Z","caller":"traceutil/trace.go:171","msg":"trace[1412864400] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"213.904679ms","start":"2026-04-17T04:35:24.361246Z","end":"2026-04-17T04:35:24.57515Z","steps":["trace[1412864400] 'process raft request' (duration: 173.09139ms)","trace[1412864400] 'compare' (duration: 40.463871ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:28.154989Z","caller":"traceutil/trace.go:171","msg":"trace[1062432499] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"159.025054ms","start":"2026-04-17T04:35:27.995935Z","end":"2026-04-17T04:35:28.15496Z","steps":["trace[1062432499] 'process raft request' (duration: 158.775678ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:32.40134Z","caller":"traceutil/trace.go:171","msg":"trace[1483679592] linearizableReadLoop","detail":"{readStateIndex:2490; appliedIndex:2489; }","duration":"130.673993ms","start":"2026-04-17T04:35:32.270643Z","end":"2026-04-17T04:35:32.401317Z","steps":["trace[1483679592] 'read index received' (duration: 130.302426ms)","trace[1483679592] 'applied index is now lower than readState.Index' (duration: 370.647µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:32.401697Z","caller":"traceutil/trace.go:171","msg":"trace[1461367915] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"224.970094ms","start":"2026-04-17T04:35:32.176688Z","end":"2026-04-17T04:35:32.401658Z","steps":["trace[1461367915] 'process raft request' (duration: 224.483233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:32.401738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.065232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:32.401805Z","caller":"traceutil/trace.go:171","msg":"trace[1918822164] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2371; }","duration":"131.217244ms","start":"2026-04-17T04:35:32.270574Z","end":"2026-04-17T04:35:32.401791Z","steps":["trace[1918822164] 'agreement among raft nodes before linearized reading' (duration: 131.093432ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:32.679353Z","caller":"traceutil/trace.go:171","msg":"trace[588422748] linearizableReadLoop","detail":"{readStateIndex:2492; appliedIndex:2491; }","duration":"237.20146ms","start":"2026-04-17T04:35:32.442129Z","end":"2026-04-17T04:35:32.67933Z","steps":["trace[588422748] 'read index received' (duration: 151.523043ms)","trace[588422748] 'applied index is now lower than readState.Index' (duration: 85.677737ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:32.679424Z","caller":"traceutil/trace.go:171","msg":"trace[1549768702] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"256.438699ms","start":"2026-04-17T04:35:32.422956Z","end":"2026-04-17T04:35:32.679394Z","steps":["trace[1549768702] 'process raft request' (duration: 170.626079ms)","trace[1549768702] 'compare' (duration: 85.643156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:32.679563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.414214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T04:35:32.679588Z","caller":"traceutil/trace.go:171","msg":"trace[1912866863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2372; }","duration":"237.458625ms","start":"2026-04-17T04:35:32.442123Z","end":"2026-04-17T04:35:32.679581Z","steps":["trace[1912866863] 'agreement among raft nodes before linearized reading' (duration: 237.326993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:32.679665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.471706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:32.679794Z","caller":"traceutil/trace.go:171","msg":"trace[2109218362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2372; }","duration":"237.67238ms","start":"2026-04-17T04:35:32.442091Z","end":"2026-04-17T04:35:32.679764Z","steps":["trace[2109218362] 'agreement among raft nodes before linearized reading' (duration: 237.454766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:32.679951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.868707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:32.680024Z","caller":"traceutil/trace.go:171","msg":"trace[486794189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2372; }","duration":"193.01898ms","start":"2026-04-17T04:35:32.486989Z","end":"2026-04-17T04:35:32.680008Z","steps":["trace[486794189] 'agreement among raft nodes before linearized reading' (duration: 192.835556ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:36.572617Z","caller":"traceutil/trace.go:171","msg":"trace[1529485168] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"100.699769ms","start":"2026-04-17T04:35:36.471892Z","end":"2026-04-17T04:35:36.572591Z","steps":["trace[1529485168] 'process raft request' (duration: 100.550186ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:36.853745Z","caller":"traceutil/trace.go:171","msg":"trace[994565281] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"116.948776ms","start":"2026-04-17T04:35:36.736775Z","end":"2026-04-17T04:35:36.853724Z","steps":["trace[994565281] 'process raft request' (duration: 116.357015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:37.176225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.323536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:37.176385Z","caller":"traceutil/trace.go:171","msg":"trace[442604815] linearizableReadLoop","detail":"{readStateIndex:2509; appliedIndex:2508; }","duration":"129.468829ms","start":"2026-04-17T04:35:37.046893Z","end":"2026-04-17T04:35:37.176362Z","steps":["trace[442604815] 'read index received' (duration: 38.591µs)","trace[442604815] 'applied index is now lower than readState.Index' (duration: 129.428088ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:37.176423Z","caller":"traceutil/trace.go:171","msg":"trace[103349439] transaction","detail":"{read_only:false; response_revision:2389; number_of_response:1; }","duration":"194.905038ms","start":"2026-04-17T04:35:36.981504Z","end":"2026-04-17T04:35:37.176409Z","steps":["trace[103349439] 'process raft request' (duration: 57.266675ms)","trace[103349439] 'compare' (duration: 137.170184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:37.176792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.904987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T04:35:37.176828Z","caller":"traceutil/trace.go:171","msg":"trace[72592974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2389; }","duration":"129.987859ms","start":"2026-04-17T04:35:37.046831Z","end":"2026-04-17T04:35:37.176819Z","steps":["trace[72592974] 'agreement among raft nodes before linearized reading' (duration: 129.583671ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:38.747891Z","caller":"traceutil/trace.go:171","msg":"trace[298338172] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"168.337942ms","start":"2026-04-17T04:35:38.579529Z","end":"2026-04-17T04:35:38.747867Z","steps":["trace[298338172] 'process raft request' (duration: 168.182309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:39.086864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.184005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:35:39.086931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.02274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:35:39.086937Z","caller":"traceutil/trace.go:171","msg":"trace[1254584525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2393; }","duration":"142.299498ms","start":"2026-04-17T04:35:38.944622Z","end":"2026-04-17T04:35:39.086922Z","steps":["trace[1254584525] 'range keys from in-memory index tree' (duration: 142.125503ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:39.08696Z","caller":"traceutil/trace.go:171","msg":"trace[1002171256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2393; }","duration":"126.06491ms","start":"2026-04-17T04:35:38.960886Z","end":"2026-04-17T04:35:39.086951Z","steps":["trace[1002171256] 'range keys from in-memory index tree' (duration: 125.915177ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:39.323605Z","caller":"traceutil/trace.go:171","msg":"trace[889843099] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"137.99017ms","start":"2026-04-17T04:35:39.185521Z","end":"2026-04-17T04:35:39.323511Z","steps":["trace[889843099] 'process raft request' (duration: 137.48741ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:41.05855Z","caller":"traceutil/trace.go:171","msg":"trace[658329394] linearizableReadLoop","detail":"{readStateIndex:2519; appliedIndex:2518; }","duration":"110.534207ms","start":"2026-04-17T04:35:40.947973Z","end":"2026-04-17T04:35:41.058507Z","steps":["trace[658329394] 'read index received' (duration: 51.873265ms)","trace[658329394] 'applied index is now lower than readState.Index' (duration: 58.658422ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:41.058587Z","caller":"traceutil/trace.go:171","msg":"trace[105582292] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"133.229175ms","start":"2026-04-17T04:35:40.925314Z","end":"2026-04-17T04:35:41.058543Z","steps":["trace[105582292] 'process raft request' (duration: 74.548702ms)","trace[105582292] 'compare' (duration: 58.435738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:41.058876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.881845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:41.058942Z","caller":"traceutil/trace.go:171","msg":"trace[2132595535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2398; }","duration":"111.010047ms","start":"2026-04-17T04:35:40.947917Z","end":"2026-04-17T04:35:41.058928Z","steps":["trace[2132595535] 'agreement among raft nodes before linearized reading' (duration: 110.752012ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:41.307033Z","caller":"traceutil/trace.go:171","msg":"trace[749284491] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"242.313222ms","start":"2026-04-17T04:35:41.064685Z","end":"2026-04-17T04:35:41.306998Z","steps":["trace[749284491] 'process raft request' (duration: 151.334909ms)","trace[749284491] 'compare' (duration: 90.8049ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:41.307092Z","caller":"traceutil/trace.go:171","msg":"trace[1997669475] linearizableReadLoop","detail":"{readStateIndex:2520; appliedIndex:2519; }","duration":"193.236864ms","start":"2026-04-17T04:35:41.113822Z","end":"2026-04-17T04:35:41.307059Z","steps":["trace[1997669475] 'read index received' (duration: 102.21329ms)","trace[1997669475] 'applied index is now lower than readState.Index' (duration: 91.022404ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:41.307362Z","caller":"traceutil/trace.go:171","msg":"trace[953688345] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"188.065681ms","start":"2026-04-17T04:35:41.119272Z","end":"2026-04-17T04:35:41.307338Z","steps":["trace[953688345] 'process raft request' (duration: 187.745823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:41.307547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.696373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:35:41.307738Z","caller":"traceutil/trace.go:171","msg":"trace[1983618296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2399; }","duration":"193.942288ms","start":"2026-04-17T04:35:41.113761Z","end":"2026-04-17T04:35:41.307703Z","steps":["trace[1983618296] 'agreement among raft nodes before linearized reading' (duration: 193.411998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:41.560912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.562934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:41.560994Z","caller":"traceutil/trace.go:171","msg":"trace[1143032702] linearizableReadLoop","detail":"{readStateIndex:2522; appliedIndex:2521; }","duration":"233.341462ms","start":"2026-04-17T04:35:41.32764Z","end":"2026-04-17T04:35:41.560981Z","steps":["trace[1143032702] 'read index received' (duration: 84.497813ms)","trace[1143032702] 'applied index is now lower than readState.Index' (duration: 148.842709ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:41.561226Z","caller":"traceutil/trace.go:171","msg":"trace[231419940] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"248.437447ms","start":"2026-04-17T04:35:41.312779Z","end":"2026-04-17T04:35:41.561217Z","steps":["trace[231419940] 'process raft request' (duration: 99.500055ms)","trace[231419940] 'compare' (duration: 148.452522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:41.561395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.771831ms","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-04-17T04:35:41.561463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.130614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T04:35:41.561529Z","caller":"traceutil/trace.go:171","msg":"trace[1848155741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2401; }","duration":"233.958674ms","start":"2026-04-17T04:35:41.327554Z","end":"2026-04-17T04:35:41.561513Z","steps":["trace[1848155741] 'agreement among raft nodes before linearized reading' (duration: 233.676679ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:41.56155Z","caller":"traceutil/trace.go:171","msg":"trace[725309622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2401; }","duration":"207.266417ms","start":"2026-04-17T04:35:41.354264Z","end":"2026-04-17T04:35:41.561531Z","steps":["trace[725309622] 'agreement among raft nodes before linearized reading' (duration: 206.978191ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:41.793561Z","caller":"traceutil/trace.go:171","msg":"trace[69757668] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"227.592466ms","start":"2026-04-17T04:35:41.56593Z","end":"2026-04-17T04:35:41.793522Z","steps":["trace[69757668] 'process raft request' (duration: 187.08602ms)","trace[69757668] 'compare' (duration: 40.381863ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:41.817864Z","caller":"traceutil/trace.go:171","msg":"trace[1521244339] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"251.55614ms","start":"2026-04-17T04:35:41.566258Z","end":"2026-04-17T04:35:41.817814Z","steps":["trace[1521244339] 'process raft request' (duration: 251.342825ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:42.527702Z","caller":"traceutil/trace.go:171","msg":"trace[1741548964] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"109.910924ms","start":"2026-04-17T04:35:42.417718Z","end":"2026-04-17T04:35:42.527629Z","steps":["trace[1741548964] 'process raft request' (duration: 46.538498ms)","trace[1741548964] 'compare' (duration: 63.07309ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:43.839319Z","caller":"traceutil/trace.go:171","msg":"trace[1736331868] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"269.530011ms","start":"2026-04-17T04:35:43.569755Z","end":"2026-04-17T04:35:43.839285Z","steps":["trace[1736331868] 'process raft request' (duration: 269.137403ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:45.26216Z","caller":"traceutil/trace.go:171","msg":"trace[107015710] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"153.472642ms","start":"2026-04-17T04:35:45.108668Z","end":"2026-04-17T04:35:45.26214Z","steps":["trace[107015710] 'process raft request' (duration: 153.343569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:45.478309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.851225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:45.478395Z","caller":"traceutil/trace.go:171","msg":"trace[83298537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2413; }","duration":"117.986299ms","start":"2026-04-17T04:35:45.36039Z","end":"2026-04-17T04:35:45.478376Z","steps":["trace[83298537] 'range keys from in-memory index tree' (duration: 117.659171ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:45.640904Z","caller":"traceutil/trace.go:171","msg":"trace[1703160484] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"157.689288ms","start":"2026-04-17T04:35:45.483176Z","end":"2026-04-17T04:35:45.640865Z","steps":["trace[1703160484] 'process raft request' (duration: 124.970099ms)","trace[1703160484] 'compare' (duration: 32.558905ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:45.966357Z","caller":"traceutil/trace.go:171","msg":"trace[2071736088] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"116.795303ms","start":"2026-04-17T04:35:45.849511Z","end":"2026-04-17T04:35:45.966307Z","steps":["trace[2071736088] 'process raft request' (duration: 116.550058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:47.424303Z","caller":"traceutil/trace.go:171","msg":"trace[315917032] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"153.411522ms","start":"2026-04-17T04:35:47.270862Z","end":"2026-04-17T04:35:47.424274Z","steps":["trace[315917032] 'process raft request' (duration: 153.230838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:47.551288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.546057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:47.551469Z","caller":"traceutil/trace.go:171","msg":"trace[435667697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2420; }","duration":"108.826973ms","start":"2026-04-17T04:35:47.442615Z","end":"2026-04-17T04:35:47.551442Z","steps":["trace[435667697] 'range keys from in-memory index tree' (duration: 108.444835ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:47.9009Z","caller":"traceutil/trace.go:171","msg":"trace[1353342102] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"250.330264ms","start":"2026-04-17T04:35:47.650541Z","end":"2026-04-17T04:35:47.900871Z","steps":["trace[1353342102] 'process raft request' (duration: 250.11481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.803207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.38554ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T04:35:48.80332Z","caller":"traceutil/trace.go:171","msg":"trace[382107149] linearizableReadLoop","detail":"{readStateIndex:2544; appliedIndex:2543; }","duration":"832.452475ms","start":"2026-04-17T04:35:47.970849Z","end":"2026-04-17T04:35:48.803302Z","steps":["trace[382107149] 'read index received' (duration: 156.862261ms)","trace[382107149] 'applied index is now lower than readState.Index' (duration: 675.588664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:48.803702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.920894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:35:48.803724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.114195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:35:48.803823Z","caller":"traceutil/trace.go:171","msg":"trace[720939104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2421; }","duration":"360.082916ms","start":"2026-04-17T04:35:48.443712Z","end":"2026-04-17T04:35:48.803795Z","steps":["trace[720939104] 'agreement among raft nodes before linearized reading' (duration: 359.916703ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:48.803862Z","caller":"traceutil/trace.go:171","msg":"trace[1191485572] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2421; }","duration":"807.280139ms","start":"2026-04-17T04:35:47.996547Z","end":"2026-04-17T04:35:48.803827Z","steps":["trace[1191485572] 'agreement among raft nodes before linearized reading' (duration: 807.077865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.803953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:47.996455Z","time spent":"807.467413ms","remote":"127.0.0.1:53480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:35:48.804013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.75087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:35:48.80406Z","caller":"traceutil/trace.go:171","msg":"trace[1960714084] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2421; }","duration":"164.841842ms","start":"2026-04-17T04:35:48.639203Z","end":"2026-04-17T04:35:48.804045Z","steps":["trace[1960714084] 'agreement among raft nodes before linearized reading' (duration: 164.76272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.803884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:48.443693Z","time spent":"360.178129ms","remote":"127.0.0.1:53258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T04:35:48.804514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.565403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:35:48.804563Z","caller":"traceutil/trace.go:171","msg":"trace[1379569463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2421; }","duration":"806.633955ms","start":"2026-04-17T04:35:47.997916Z","end":"2026-04-17T04:35:48.80455Z","steps":["trace[1379569463] 'agreement among raft nodes before linearized reading' (duration: 806.452741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.804604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:47.997905Z","time spent":"806.689286ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:35:48.804618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.90749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T04:35:48.804702Z","caller":"traceutil/trace.go:171","msg":"trace[1817280253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2421; }","duration":"806.004572ms","start":"2026-04-17T04:35:47.99867Z","end":"2026-04-17T04:35:48.804675Z","steps":["trace[1817280253] 'agreement among raft nodes before linearized reading' (duration: 805.736667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.804929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:47.998663Z","time spent":"806.078724ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:35:48.805611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.758532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:35:48.805663Z","caller":"traceutil/trace.go:171","msg":"trace[1606042372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2421; }","duration":"834.862894ms","start":"2026-04-17T04:35:47.970784Z","end":"2026-04-17T04:35:48.805647Z","steps":["trace[1606042372] 'agreement among raft nodes before linearized reading' (duration: 832.614998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:48.805699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:47.970758Z","time spent":"834.932956ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T04:35:49.114044Z","caller":"traceutil/trace.go:171","msg":"trace[1039576386] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"304.054547ms","start":"2026-04-17T04:35:48.809955Z","end":"2026-04-17T04:35:49.114009Z","steps":["trace[1039576386] 'process raft request' (duration: 303.74668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:49.114263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:48.809939Z","time spent":"304.201499ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:35:49.156619Z","caller":"traceutil/trace.go:171","msg":"trace[988322039] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"346.119116ms","start":"2026-04-17T04:35:48.810469Z","end":"2026-04-17T04:35:49.156588Z","steps":["trace[988322039] 'process raft request' (duration: 346.032844ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:49.156673Z","caller":"traceutil/trace.go:171","msg":"trace[331630524] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"346.567975ms","start":"2026-04-17T04:35:48.810078Z","end":"2026-04-17T04:35:49.156646Z","steps":["trace[331630524] 'process raft request' (duration: 346.261678ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:49.156793Z","caller":"traceutil/trace.go:171","msg":"trace[2018566190] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"152.988643ms","start":"2026-04-17T04:35:49.003774Z","end":"2026-04-17T04:35:49.156762Z","steps":["trace[2018566190] 'process raft request' (duration: 152.770509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:49.156816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:48.810449Z","time spent":"346.262828ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:49.156827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:48.810062Z","time spent":"346.668426ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:49.80225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.256511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T04:35:49.802338Z","caller":"traceutil/trace.go:171","msg":"trace[1517700296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2425; }","duration":"373.405824ms","start":"2026-04-17T04:35:49.428911Z","end":"2026-04-17T04:35:49.802317Z","steps":["trace[1517700296] 'range keys from in-memory index tree' (duration: 373.045468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:49.802385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:49.428887Z","time spent":"373.488387ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T04:35:50.192592Z","caller":"traceutil/trace.go:171","msg":"trace[649206743] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2548; }","duration":"285.482843ms","start":"2026-04-17T04:35:49.907074Z","end":"2026-04-17T04:35:50.192557Z","steps":["trace[649206743] 'read index received' (duration: 285.249928ms)","trace[649206743] 'applied index is now lower than readState.Index' (duration: 231.445µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:50.192695Z","caller":"traceutil/trace.go:171","msg":"trace[541370359] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"384.904316ms","start":"2026-04-17T04:35:49.807758Z","end":"2026-04-17T04:35:50.192663Z","steps":["trace[541370359] 'process raft request' (duration: 384.59001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:50.192862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:49.807726Z","time spent":"385.045009ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:50.192861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.755108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:50.192926Z","caller":"traceutil/trace.go:171","msg":"trace[1574105394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2426; }","duration":"285.890321ms","start":"2026-04-17T04:35:49.907018Z","end":"2026-04-17T04:35:50.192908Z","steps":["trace[1574105394] 'agreement among raft nodes before linearized reading' (duration: 285.671137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:50.540044Z","caller":"traceutil/trace.go:171","msg":"trace[1711172085] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"342.692226ms","start":"2026-04-17T04:35:50.197302Z","end":"2026-04-17T04:35:50.539994Z","steps":["trace[1711172085] 'process raft request' (duration: 339.557022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:50.540292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:50.19728Z","time spent":"342.88003ms","remote":"127.0.0.1:53550","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-04-17T04:35:51.265895Z","caller":"traceutil/trace.go:171","msg":"trace[2027863646] linearizableReadLoop","detail":"{readStateIndex:2551; appliedIndex:2550; }","duration":"104.788501ms","start":"2026-04-17T04:35:51.16107Z","end":"2026-04-17T04:35:51.265859Z","steps":["trace[2027863646] 'read index received' (duration: 104.589008ms)","trace[2027863646] 'applied index is now lower than readState.Index' (duration: 197.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:51.265929Z","caller":"traceutil/trace.go:171","msg":"trace[144247151] transaction","detail":"{read_only:false; response_revision:2428; number_of_response:1; }","duration":"142.009871ms","start":"2026-04-17T04:35:51.12387Z","end":"2026-04-17T04:35:51.26588Z","steps":["trace[144247151] 'process raft request' (duration: 141.703485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:51.266375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.238741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:35:51.266501Z","caller":"traceutil/trace.go:171","msg":"trace[568995327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2428; }","duration":"105.442015ms","start":"2026-04-17T04:35:51.161032Z","end":"2026-04-17T04:35:51.266474Z","steps":["trace[568995327] 'agreement among raft nodes before linearized reading' (duration: 104.972335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:51.862809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.275744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:51.863021Z","caller":"traceutil/trace.go:171","msg":"trace[1368636996] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"701.268212ms","start":"2026-04-17T04:35:51.161713Z","end":"2026-04-17T04:35:51.862981Z","steps":["trace[1368636996] 'process raft request' (duration: 185.622012ms)","trace[1368636996] 'compare' (duration: 515.012787ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:51.863191Z","caller":"traceutil/trace.go:171","msg":"trace[459462666] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"591.156173ms","start":"2026-04-17T04:35:51.272004Z","end":"2026-04-17T04:35:51.86316Z","steps":["trace[459462666] 'process raft request' (duration: 590.99343ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:51.863258Z","caller":"traceutil/trace.go:171","msg":"trace[17804254] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2551; }","duration":"597.250405ms","start":"2026-04-17T04:35:51.26598Z","end":"2026-04-17T04:35:51.86323Z","steps":["trace[17804254] 'read index received' (duration: 81.309659ms)","trace[17804254] 'applied index is now lower than readState.Index' (duration: 515.937866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:51.863281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:51.161691Z","time spent":"701.455946ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:51.863299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:51.271972Z","time spent":"591.279445ms","remote":"127.0.0.1:53550","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-04-17T04:35:51.863605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.471236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:35:51.863716Z","caller":"traceutil/trace.go:171","msg":"trace[1717098410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2430; }","duration":"702.629219ms","start":"2026-04-17T04:35:51.161059Z","end":"2026-04-17T04:35:51.863688Z","steps":["trace[1717098410] 'agreement among raft nodes before linearized reading' (duration: 702.259452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:51.863779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:51.161042Z","time spent":"702.726931ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T04:35:51.89353Z","caller":"traceutil/trace.go:171","msg":"trace[1278368680] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"497.444505ms","start":"2026-04-17T04:35:51.396055Z","end":"2026-04-17T04:35:51.8935Z","steps":["trace[1278368680] 'process raft request' (duration: 497.164238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:51.893728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:51.396007Z","time spent":"497.601558ms","remote":"127.0.0.1:53550","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-04-17T04:35:52.297292Z","caller":"traceutil/trace.go:171","msg":"trace[1975932734] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"429.161179ms","start":"2026-04-17T04:35:51.868077Z","end":"2026-04-17T04:35:52.297238Z","steps":["trace[1975932734] 'process raft request' (duration: 358.987445ms)","trace[1975932734] 'compare' (duration: 69.408108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:52.297558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:51.868053Z","time spent":"429.380583ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:53.370333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.40346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:53.37043Z","caller":"traceutil/trace.go:171","msg":"trace[207439405] linearizableReadLoop","detail":"{readStateIndex:2556; appliedIndex:2555; }","duration":"980.01621ms","start":"2026-04-17T04:35:52.390399Z","end":"2026-04-17T04:35:53.370415Z","steps":["trace[207439405] 'read index received' (duration: 246.434966ms)","trace[207439405] 'applied index is now lower than readState.Index' (duration: 733.579944ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:53.370505Z","caller":"traceutil/trace.go:171","msg":"trace[129394972] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"1.067878509s","start":"2026-04-17T04:35:52.30262Z","end":"2026-04-17T04:35:53.370498Z","steps":["trace[129394972] 'process raft request' (duration: 334.224315ms)","trace[129394972] 'compare' (duration: 733.278087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:53.370541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.302591Z","time spent":"1.067930761s","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:53.370735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.904309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:35:53.370814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.089743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:53.370837Z","caller":"traceutil/trace.go:171","msg":"trace[1473305365] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2433; }","duration":"405.184526ms","start":"2026-04-17T04:35:52.965646Z","end":"2026-04-17T04:35:53.370831Z","steps":["trace[1473305365] 'agreement among raft nodes before linearized reading' (duration: 405.097354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.370855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.965623Z","time spent":"405.227686ms","remote":"127.0.0.1:53426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-17T04:35:53.370841Z","caller":"traceutil/trace.go:171","msg":"trace[437995036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2433; }","duration":"927.073182ms","start":"2026-04-17T04:35:52.443738Z","end":"2026-04-17T04:35:53.370811Z","steps":["trace[437995036] 'agreement among raft nodes before linearized reading' (duration: 926.883599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.370906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.500389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-17T04:35:53.370918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.443712Z","time spent":"927.189064ms","remote":"127.0.0.1:53258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T04:35:53.37097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.894033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:53.370986Z","caller":"traceutil/trace.go:171","msg":"trace[1553324728] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:2433; }","duration":"980.626511ms","start":"2026-04-17T04:35:52.390337Z","end":"2026-04-17T04:35:53.370963Z","steps":["trace[1553324728] 'agreement among raft nodes before linearized reading' (duration: 980.415917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.370959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.154087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T04:35:53.371028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.39031Z","time spent":"980.709682ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-04-17T04:35:53.37103Z","caller":"traceutil/trace.go:171","msg":"trace[981690880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2433; }","duration":"826.010935ms","start":"2026-04-17T04:35:52.544999Z","end":"2026-04-17T04:35:53.37101Z","steps":["trace[981690880] 'agreement among raft nodes before linearized reading' (duration: 825.788601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.371083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.544972Z","time spent":"826.099388ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T04:35:53.371091Z","caller":"traceutil/trace.go:171","msg":"trace[1129526018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2433; }","duration":"878.364401ms","start":"2026-04-17T04:35:52.492692Z","end":"2026-04-17T04:35:53.371056Z","steps":["trace[1129526018] 'agreement among raft nodes before linearized reading' (duration: 878.112846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.371055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.388183ms","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-04-17T04:35:53.37116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:52.492665Z","time spent":"878.475583ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-17T04:35:53.371187Z","caller":"traceutil/trace.go:171","msg":"trace[2090290392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2433; }","duration":"100.569536ms","start":"2026-04-17T04:35:53.27059Z","end":"2026-04-17T04:35:53.37116Z","steps":["trace[2090290392] 'agreement among raft nodes before linearized reading' (duration: 100.219799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.831449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.565486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:53.831594Z","caller":"traceutil/trace.go:171","msg":"trace[1224293088] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"455.658082ms","start":"2026-04-17T04:35:53.37592Z","end":"2026-04-17T04:35:53.831578Z","steps":["trace[1224293088] 'process raft request' (duration: 223.891742ms)","trace[1224293088] 'compare' (duration: 231.390563ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:53.832114Z","caller":"traceutil/trace.go:171","msg":"trace[1977759173] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"452.704903ms","start":"2026-04-17T04:35:53.379398Z","end":"2026-04-17T04:35:53.832103Z","steps":["trace[1977759173] 'process raft request' (duration: 452.469968ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:53.832154Z","caller":"traceutil/trace.go:171","msg":"trace[658282035] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"455.760835ms","start":"2026-04-17T04:35:53.376373Z","end":"2026-04-17T04:35:53.832134Z","steps":["trace[658282035] 'process raft request' (duration: 455.162822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:53.832174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:53.379379Z","time spent":"452.764624ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:35:53.832245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:53.376356Z","time spent":"455.842216ms","remote":"127.0.0.1:53550","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-04-17T04:35:53.832378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:53.375888Z","time spent":"455.733033ms","remote":"127.0.0.1:53550","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-04-17T04:35:54.198505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.762053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:35:54.198661Z","caller":"traceutil/trace.go:171","msg":"trace[304929846] linearizableReadLoop","detail":"{readStateIndex:2561; appliedIndex:2560; }","duration":"329.598292ms","start":"2026-04-17T04:35:53.869022Z","end":"2026-04-17T04:35:54.19862Z","steps":["trace[304929846] 'read index received' (duration: 146.587634ms)","trace[304929846] 'applied index is now lower than readState.Index' (duration: 183.008748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:54.198866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.845816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T04:35:54.198926Z","caller":"traceutil/trace.go:171","msg":"trace[429336426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2437; }","duration":"329.964119ms","start":"2026-04-17T04:35:53.868945Z","end":"2026-04-17T04:35:54.198909Z","steps":["trace[429336426] 'agreement among raft nodes before linearized reading' (duration: 329.758805ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:54.198878Z","caller":"traceutil/trace.go:171","msg":"trace[1719709429] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"330.02599ms","start":"2026-04-17T04:35:53.868805Z","end":"2026-04-17T04:35:54.198831Z","steps":["trace[1719709429] 'process raft request' (duration: 146.831308ms)","trace[1719709429] 'compare' (duration: 182.498428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:54.198983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:53.868914Z","time spent":"330.049051ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:35:54.199128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:35:53.868755Z","time spent":"330.245605ms","remote":"127.0.0.1:53550","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-04-17T04:35:54.418307Z","caller":"traceutil/trace.go:171","msg":"trace[703001776] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"116.735213ms","start":"2026-04-17T04:35:54.301536Z","end":"2026-04-17T04:35:54.418271Z","steps":["trace[703001776] 'read index received' (duration: 116.325164ms)","trace[703001776] 'applied index is now lower than readState.Index' (duration: 408.269µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:54.418508Z","caller":"traceutil/trace.go:171","msg":"trace[963344533] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"215.293099ms","start":"2026-04-17T04:35:54.203181Z","end":"2026-04-17T04:35:54.418474Z","steps":["trace[963344533] 'process raft request' (duration: 214.825299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:54.41865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.07857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T04:35:54.418698Z","caller":"traceutil/trace.go:171","msg":"trace[720463960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2438; }","duration":"117.189252ms","start":"2026-04-17T04:35:54.301496Z","end":"2026-04-17T04:35:54.418686Z","steps":["trace[720463960] 'agreement among raft nodes before linearized reading' (duration: 116.953577ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:55.572441Z","caller":"traceutil/trace.go:171","msg":"trace[849695294] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"191.898587ms","start":"2026-04-17T04:35:55.380518Z","end":"2026-04-17T04:35:55.572417Z","steps":["trace[849695294] 'process raft request' (duration: 191.742454ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:56.018819Z","caller":"traceutil/trace.go:171","msg":"trace[1024997752] linearizableReadLoop","detail":"{readStateIndex:2565; appliedIndex:2564; }","duration":"222.081545ms","start":"2026-04-17T04:35:55.796703Z","end":"2026-04-17T04:35:56.018785Z","steps":["trace[1024997752] 'read index received' (duration: 206.698275ms)","trace[1024997752] 'applied index is now lower than readState.Index' (duration: 15.38175ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:35:56.019337Z","caller":"traceutil/trace.go:171","msg":"trace[622939107] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"259.305815ms","start":"2026-04-17T04:35:55.759932Z","end":"2026-04-17T04:35:56.019238Z","steps":["trace[622939107] 'process raft request' (duration: 243.546958ms)","trace[622939107] 'compare' (duration: 14.475061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:35:56.019373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.596775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:35:56.019492Z","caller":"traceutil/trace.go:171","msg":"trace[51894109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2441; }","duration":"222.823599ms","start":"2026-04-17T04:35:55.796642Z","end":"2026-04-17T04:35:56.019465Z","steps":["trace[51894109] 'agreement among raft nodes before linearized reading' (duration: 222.514433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:56.019503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.148411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:35:56.019619Z","caller":"traceutil/trace.go:171","msg":"trace[1632395925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2441; }","duration":"183.423036ms","start":"2026-04-17T04:35:55.836181Z","end":"2026-04-17T04:35:56.019604Z","steps":["trace[1632395925] 'agreement among raft nodes before linearized reading' (duration: 183.043029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:35:56.01961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.787604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T04:35:56.019705Z","caller":"traceutil/trace.go:171","msg":"trace[540737732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2441; }","duration":"182.967917ms","start":"2026-04-17T04:35:55.836719Z","end":"2026-04-17T04:35:56.019687Z","steps":["trace[540737732] 'agreement among raft nodes before linearized reading' (duration: 182.714052ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:35:56.343847Z","caller":"traceutil/trace.go:171","msg":"trace[1527934226] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"139.147014ms","start":"2026-04-17T04:35:56.204675Z","end":"2026-04-17T04:35:56.343822Z","steps":["trace[1527934226] 'process raft request' (duration: 139.016801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:22.520696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.592991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:22.521014Z","caller":"traceutil/trace.go:171","msg":"trace[1772674755] linearizableReadLoop","detail":"{readStateIndex:2658; appliedIndex:2657; }","duration":"129.420989ms","start":"2026-04-17T04:36:22.391529Z","end":"2026-04-17T04:36:22.52095Z","steps":["trace[1772674755] 'read index received' (duration: 46.301µs)","trace[1772674755] 'applied index is now lower than readState.Index' (duration: 129.372808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:22.521264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.713794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T04:36:22.521278Z","caller":"traceutil/trace.go:171","msg":"trace[1886885971] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"303.831442ms","start":"2026-04-17T04:36:22.217356Z","end":"2026-04-17T04:36:22.521187Z","steps":["trace[1886885971] 'process raft request' (duration: 173.618228ms)","trace[1886885971] 'compare' (duration: 129.387307ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:22.521359Z","caller":"traceutil/trace.go:171","msg":"trace[994944098] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:2529; }","duration":"129.868937ms","start":"2026-04-17T04:36:22.391467Z","end":"2026-04-17T04:36:22.521336Z","steps":["trace[994944098] 'agreement among raft nodes before linearized reading' (duration: 129.606132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:22.52153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:22.217331Z","time spent":"304.059228ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:36:22.726707Z","caller":"traceutil/trace.go:171","msg":"trace[989860957] linearizableReadLoop","detail":"{readStateIndex:2662; appliedIndex:2661; }","duration":"159.067106ms","start":"2026-04-17T04:36:22.5676Z","end":"2026-04-17T04:36:22.726667Z","steps":["trace[989860957] 'read index received' (duration: 112.376245ms)","trace[989860957] 'applied index is now lower than readState.Index' (duration: 46.689591ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:22.726956Z","caller":"traceutil/trace.go:171","msg":"trace[1460853729] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"160.234598ms","start":"2026-04-17T04:36:22.566708Z","end":"2026-04-17T04:36:22.726943Z","steps":["trace[1460853729] 'process raft request' (duration: 113.391924ms)","trace[1460853729] 'compare' (duration: 46.157081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:22.727174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.566776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-17T04:36:22.72721Z","caller":"traceutil/trace.go:171","msg":"trace[1166308879] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2532; }","duration":"159.654427ms","start":"2026-04-17T04:36:22.567543Z","end":"2026-04-17T04:36:22.727198Z","steps":["trace[1166308879] 'agreement among raft nodes before linearized reading' (duration: 159.569396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:23.014773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.595844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:23.014894Z","caller":"traceutil/trace.go:171","msg":"trace[1909245889] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"282.445432ms","start":"2026-04-17T04:36:22.732427Z","end":"2026-04-17T04:36:23.014872Z","steps":["trace[1909245889] 'process raft request' (duration: 136.642894ms)","trace[1909245889] 'compare' (duration: 145.464891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:24.599157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.075585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:36:24.599257Z","caller":"traceutil/trace.go:171","msg":"trace[206230069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2535; }","duration":"156.235268ms","start":"2026-04-17T04:36:24.443001Z","end":"2026-04-17T04:36:24.599236Z","steps":["trace[206230069] 'range keys from in-memory index tree' (duration: 155.968932ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:24.6318Z","caller":"traceutil/trace.go:171","msg":"trace[1889400730] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"102.974386ms","start":"2026-04-17T04:36:24.528787Z","end":"2026-04-17T04:36:24.631762Z","steps":["trace[1889400730] 'process raft request' (duration: 102.7071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:24.938222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.015574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:24.938372Z","caller":"traceutil/trace.go:171","msg":"trace[341292287] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"206.202215ms","start":"2026-04-17T04:36:24.73215Z","end":"2026-04-17T04:36:24.938352Z","steps":["trace[341292287] 'read index received' (duration: 41.832703ms)","trace[341292287] 'applied index is now lower than readState.Index' (duration: 164.367922ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:24.938534Z","caller":"traceutil/trace.go:171","msg":"trace[443144063] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"335.156884ms","start":"2026-04-17T04:36:24.603334Z","end":"2026-04-17T04:36:24.938491Z","steps":["trace[443144063] 'process raft request' (duration: 170.741341ms)","trace[443144063] 'compare' (duration: 163.80969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:24.938665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.481901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:36:24.938697Z","caller":"traceutil/trace.go:171","msg":"trace[1848306639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2537; }","duration":"206.611414ms","start":"2026-04-17T04:36:24.732078Z","end":"2026-04-17T04:36:24.938689Z","steps":["trace[1848306639] 'agreement among raft nodes before linearized reading' (duration: 206.327398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:24.938724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:24.603316Z","time spent":"335.305937ms","remote":"127.0.0.1:53550","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-04-17T04:36:25.252586Z","caller":"traceutil/trace.go:171","msg":"trace[485023865] linearizableReadLoop","detail":"{readStateIndex:2668; appliedIndex:2667; }","duration":"231.977165ms","start":"2026-04-17T04:36:25.020568Z","end":"2026-04-17T04:36:25.252546Z","steps":["trace[485023865] 'read index received' (duration: 231.694209ms)","trace[485023865] 'applied index is now lower than readState.Index' (duration: 277.516µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:25.252614Z","caller":"traceutil/trace.go:171","msg":"trace[1059847997] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"307.501907ms","start":"2026-04-17T04:36:24.945071Z","end":"2026-04-17T04:36:25.252573Z","steps":["trace[1059847997] 'process raft request' (duration: 307.241492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:25.252841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.24362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T04:36:25.252882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:24.945037Z","time spent":"307.70112ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:36:25.252932Z","caller":"traceutil/trace.go:171","msg":"trace[148883252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2538; }","duration":"232.396143ms","start":"2026-04-17T04:36:25.020513Z","end":"2026-04-17T04:36:25.252909Z","steps":["trace[148883252] 'agreement among raft nodes before linearized reading' (duration: 232.126428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:25.518226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.719084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:25.518377Z","caller":"traceutil/trace.go:171","msg":"trace[1039178298] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"260.815877ms","start":"2026-04-17T04:36:25.257535Z","end":"2026-04-17T04:36:25.518351Z","steps":["trace[1039178298] 'process raft request' (duration: 131.288046ms)","trace[1039178298] 'compare' (duration: 128.559141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:26.892123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.623944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:26.892261Z","caller":"traceutil/trace.go:171","msg":"trace[1862230685] linearizableReadLoop","detail":"{readStateIndex:2673; appliedIndex:2672; }","duration":"179.354065ms","start":"2026-04-17T04:36:26.712886Z","end":"2026-04-17T04:36:26.89224Z","steps":["trace[1862230685] 'read index received' (duration: 36.531217ms)","trace[1862230685] 'applied index is now lower than readState.Index' (duration: 142.821348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:26.892356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.465407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:36:26.892398Z","caller":"traceutil/trace.go:171","msg":"trace[1826695742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2543; }","duration":"179.554789ms","start":"2026-04-17T04:36:26.712827Z","end":"2026-04-17T04:36:26.892382Z","steps":["trace[1826695742] 'agreement among raft nodes before linearized reading' (duration: 179.471547ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:26.892395Z","caller":"traceutil/trace.go:171","msg":"trace[1043285119] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"254.752634ms","start":"2026-04-17T04:36:26.637578Z","end":"2026-04-17T04:36:26.892331Z","steps":["trace[1043285119] 'process raft request' (duration: 111.807933ms)","trace[1043285119] 'compare' (duration: 142.291538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:27.669243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.206966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T04:36:27.669325Z","caller":"traceutil/trace.go:171","msg":"trace[1031976688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2545; }","duration":"147.306108ms","start":"2026-04-17T04:36:27.522Z","end":"2026-04-17T04:36:27.669306Z","steps":["trace[1031976688] 'range keys from in-memory index tree' (duration: 147.027333ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:27.835617Z","caller":"traceutil/trace.go:171","msg":"trace[1317505590] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"161.511905ms","start":"2026-04-17T04:36:27.674084Z","end":"2026-04-17T04:36:27.835596Z","steps":["trace[1317505590] 'process raft request' (duration: 161.386073ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:27.992447Z","caller":"traceutil/trace.go:171","msg":"trace[1289218428] linearizableReadLoop","detail":"{readStateIndex:2677; appliedIndex:2676; }","duration":"122.735723ms","start":"2026-04-17T04:36:27.869684Z","end":"2026-04-17T04:36:27.992419Z","steps":["trace[1289218428] 'read index received' (duration: 71.301106ms)","trace[1289218428] 'applied index is now lower than readState.Index' (duration: 51.433027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:27.992692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.995279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T04:36:27.992784Z","caller":"traceutil/trace.go:171","msg":"trace[1354147296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2546; }","duration":"123.098841ms","start":"2026-04-17T04:36:27.869662Z","end":"2026-04-17T04:36:27.992761Z","steps":["trace[1354147296] 'agreement among raft nodes before linearized reading' (duration: 122.843326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:29.131327Z","caller":"traceutil/trace.go:171","msg":"trace[469585624] linearizableReadLoop","detail":"{readStateIndex:2680; appliedIndex:2679; }","duration":"105.20773ms","start":"2026-04-17T04:36:29.026094Z","end":"2026-04-17T04:36:29.131301Z","steps":["trace[469585624] 'read index received' (duration: 104.986246ms)","trace[469585624] 'applied index is now lower than readState.Index' (duration: 220.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:29.131431Z","caller":"traceutil/trace.go:171","msg":"trace[1651809656] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"232.110928ms","start":"2026-04-17T04:36:28.899266Z","end":"2026-04-17T04:36:29.131377Z","steps":["trace[1651809656] 'process raft request' (duration: 231.879073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:29.131535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.431344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T04:36:29.131571Z","caller":"traceutil/trace.go:171","msg":"trace[1870104980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2549; }","duration":"105.532516ms","start":"2026-04-17T04:36:29.026028Z","end":"2026-04-17T04:36:29.131561Z","steps":["trace[1870104980] 'agreement among raft nodes before linearized reading' (duration: 105.372173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:29.547636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.242567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T04:36:29.547719Z","caller":"traceutil/trace.go:171","msg":"trace[1612585090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2550; }","duration":"274.372658ms","start":"2026-04-17T04:36:29.27333Z","end":"2026-04-17T04:36:29.547703Z","steps":["trace[1612585090] 'range keys from in-memory index tree' (duration: 274.073343ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:29.68814Z","caller":"traceutil/trace.go:171","msg":"trace[1565208602] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"136.131024ms","start":"2026-04-17T04:36:29.551985Z","end":"2026-04-17T04:36:29.688116Z","steps":["trace[1565208602] 'process raft request' (duration: 135.973561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:29.968299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.451459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:36:29.968362Z","caller":"traceutil/trace.go:171","msg":"trace[657307440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2551; }","duration":"128.557851ms","start":"2026-04-17T04:36:29.83979Z","end":"2026-04-17T04:36:29.968348Z","steps":["trace[657307440] 'range keys from in-memory index tree' (duration: 128.288075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:30.398689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.955898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:30.398915Z","caller":"traceutil/trace.go:171","msg":"trace[599205241] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"353.30723ms","start":"2026-04-17T04:36:30.045558Z","end":"2026-04-17T04:36:30.398865Z","steps":["trace[599205241] 'process raft request' (duration: 164.077586ms)","trace[599205241] 'compare' (duration: 188.847786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:30.399138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:30.045534Z","time spent":"353.482403ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:36:30.750393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.967857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:30.750503Z","caller":"traceutil/trace.go:171","msg":"trace[814071628] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"307.904335ms","start":"2026-04-17T04:36:30.442583Z","end":"2026-04-17T04:36:30.750487Z","steps":["trace[814071628] 'read index received' (duration: 57.794145ms)","trace[814071628] 'applied index is now lower than readState.Index' (duration: 250.10913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:30.750588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.017377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:36:30.750614Z","caller":"traceutil/trace.go:171","msg":"trace[241573203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2554; }","duration":"308.078188ms","start":"2026-04-17T04:36:30.442526Z","end":"2026-04-17T04:36:30.750604Z","steps":["trace[241573203] 'agreement among raft nodes before linearized reading' (duration: 308.018687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:30.750643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:30.442504Z","time spent":"308.132749ms","remote":"127.0.0.1:53258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:36:30.750801Z","caller":"traceutil/trace.go:171","msg":"trace[2122383613] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"347.432841ms","start":"2026-04-17T04:36:30.403325Z","end":"2026-04-17T04:36:30.750758Z","steps":["trace[2122383613] 'process raft request' (duration: 97.024696ms)","trace[2122383613] 'compare' (duration: 249.866455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:30.75098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:30.403296Z","time spent":"347.586595ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:36:31.50118Z","caller":"traceutil/trace.go:171","msg":"trace[41741893] linearizableReadLoop","detail":"{readStateIndex:2686; appliedIndex:2685; }","duration":"299.865922ms","start":"2026-04-17T04:36:31.201291Z","end":"2026-04-17T04:36:31.501157Z","steps":["trace[41741893] 'read index received' (duration: 299.686529ms)","trace[41741893] 'applied index is now lower than readState.Index' (duration: 178.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:31.50122Z","caller":"traceutil/trace.go:171","msg":"trace[2080435558] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"364.662878ms","start":"2026-04-17T04:36:31.136537Z","end":"2026-04-17T04:36:31.5012Z","steps":["trace[2080435558] 'process raft request' (duration: 364.425704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:31.501432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.121967ms","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-04-17T04:36:31.501495Z","caller":"traceutil/trace.go:171","msg":"trace[151328533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2555; }","duration":"300.23703ms","start":"2026-04-17T04:36:31.201243Z","end":"2026-04-17T04:36:31.50148Z","steps":["trace[151328533] 'agreement among raft nodes before linearized reading' (duration: 300.052456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:31.501528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:31.201218Z","time spent":"300.302932ms","remote":"127.0.0.1:53550","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-04-17T04:36:31.501592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:31.136513Z","time spent":"364.760351ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:36:31.850494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.376874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:31.850634Z","caller":"traceutil/trace.go:171","msg":"trace[1565301614] linearizableReadLoop","detail":"{readStateIndex:2687; appliedIndex:2686; }","duration":"158.080766ms","start":"2026-04-17T04:36:31.692534Z","end":"2026-04-17T04:36:31.850615Z","steps":["trace[1565301614] 'read index received' (duration: 34.351µs)","trace[1565301614] 'applied index is now lower than readState.Index' (duration: 158.045124ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:31.850661Z","caller":"traceutil/trace.go:171","msg":"trace[2046399813] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"345.590724ms","start":"2026-04-17T04:36:31.505035Z","end":"2026-04-17T04:36:31.850626Z","steps":["trace[2046399813] 'process raft request' (duration: 173.965226ms)","trace[2046399813] 'compare' (duration: 171.209279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:31.850794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.265909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:36:31.850823Z","caller":"traceutil/trace.go:171","msg":"trace[1946115307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2556; }","duration":"158.322849ms","start":"2026-04-17T04:36:31.692492Z","end":"2026-04-17T04:36:31.850815Z","steps":["trace[1946115307] 'agreement among raft nodes before linearized reading' (duration: 158.173687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:31.85083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:31.505009Z","time spent":"345.715846ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:36:32.039024Z","caller":"traceutil/trace.go:171","msg":"trace[1635726739] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"183.657961ms","start":"2026-04-17T04:36:31.855289Z","end":"2026-04-17T04:36:32.038947Z","steps":["trace[1635726739] 'process raft request' (duration: 175.70444ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:36:32.257009Z","caller":"traceutil/trace.go:171","msg":"trace[1637703523] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"209.283428ms","start":"2026-04-17T04:36:32.047696Z","end":"2026-04-17T04:36:32.25698Z","steps":["trace[1637703523] 'process raft request' (duration: 185.776084ms)","trace[1637703523] 'compare' (duration: 23.392971ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:36:53.016531Z","caller":"traceutil/trace.go:171","msg":"trace[828327654] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"156.609368ms","start":"2026-04-17T04:36:52.859898Z","end":"2026-04-17T04:36:53.016507Z","steps":["trace[828327654] 'process raft request' (duration: 156.429113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:36:53.331605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.296212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:36:53.331723Z","caller":"traceutil/trace.go:171","msg":"trace[809408264] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"330.702679ms","start":"2026-04-17T04:36:53.001001Z","end":"2026-04-17T04:36:53.331704Z","steps":["trace[809408264] 'process raft request' (duration: 170.207323ms)","trace[809408264] 'compare' (duration: 160.15309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:36:53.331803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:36:53.000977Z","time spent":"330.797012ms","remote":"127.0.0.1:53550","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-04-17T04:36:59.277622Z","caller":"traceutil/trace.go:171","msg":"trace[400492211] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"234.7957ms","start":"2026-04-17T04:36:59.042794Z","end":"2026-04-17T04:36:59.277589Z","steps":["trace[400492211] 'process raft request' (duration: 234.606795ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:04.865212Z","caller":"traceutil/trace.go:171","msg":"trace[744523562] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"215.417368ms","start":"2026-04-17T04:37:04.649773Z","end":"2026-04-17T04:37:04.86519Z","steps":["trace[744523562] 'process raft request' (duration: 215.261524ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:05.432983Z","caller":"traceutil/trace.go:171","msg":"trace[263147193] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"124.295885ms","start":"2026-04-17T04:37:05.308656Z","end":"2026-04-17T04:37:05.432952Z","steps":["trace[263147193] 'process raft request' (duration: 124.128842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:09.259385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1706} {"level":"info","ts":"2026-04-17T04:37:09.407532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1706,"took":"147.418533ms","hash":2639530936,"current-db-size-bytes":23859200,"current-db-size":"24 MB","current-db-size-in-use-bytes":5496832,"current-db-size-in-use":"5.5 MB"} {"level":"info","ts":"2026-04-17T04:37:09.407615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639530936,"revision":1706,"compact-revision":-1} {"level":"info","ts":"2026-04-17T04:37:11.069127Z","caller":"traceutil/trace.go:171","msg":"trace[1674181201] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"325.347901ms","start":"2026-04-17T04:37:10.743724Z","end":"2026-04-17T04:37:11.069071Z","steps":["trace[1674181201] 'process raft request' (duration: 301.508828ms)","trace[1674181201] 'compare' (duration: 23.611788ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:11.069127Z","caller":"traceutil/trace.go:171","msg":"trace[580864534] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2878; }","duration":"324.198648ms","start":"2026-04-17T04:37:10.744851Z","end":"2026-04-17T04:37:11.06905Z","steps":["trace[580864534] 'read index received' (duration: 300.429937ms)","trace[580864534] 'applied index is now lower than readState.Index' (duration: 23.767251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:37:11.069254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:10.743711Z","time spent":"325.479404ms","remote":"127.0.0.1:53480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1921,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:37:11.069535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.649037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:107422"} {"level":"info","ts":"2026-04-17T04:37:11.069609Z","caller":"traceutil/trace.go:171","msg":"trace[11657070] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2737; }","duration":"324.78475ms","start":"2026-04-17T04:37:10.744805Z","end":"2026-04-17T04:37:11.069589Z","steps":["trace[11657070] 'agreement among raft nodes before linearized reading' (duration: 324.494444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:10.744784Z","time spent":"324.867752ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":107445,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-17T04:37:11.069743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.833776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:50068"} {"level":"info","ts":"2026-04-17T04:37:11.069819Z","caller":"traceutil/trace.go:171","msg":"trace[953432937] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:2737; }","duration":"301.942108ms","start":"2026-04-17T04:37:10.767862Z","end":"2026-04-17T04:37:11.069805Z","steps":["trace[953432937] 'agreement among raft nodes before linearized reading' (duration: 301.696193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.957189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10239"} {"level":"warn","ts":"2026-04-17T04:37:11.069889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.81497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-04-17T04:37:11.069917Z","caller":"traceutil/trace.go:171","msg":"trace[1338257548] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2737; }","duration":"261.04105ms","start":"2026-04-17T04:37:10.80886Z","end":"2026-04-17T04:37:11.069901Z","steps":["trace[1338257548] 'agreement among raft nodes before linearized reading' (duration: 260.905798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.375716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:50068"} {"level":"info","ts":"2026-04-17T04:37:11.069943Z","caller":"traceutil/trace.go:171","msg":"trace[568379272] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:2737; }","duration":"144.910192ms","start":"2026-04-17T04:37:10.925022Z","end":"2026-04-17T04:37:11.069932Z","steps":["trace[568379272] 'agreement among raft nodes before linearized reading' (duration: 144.716358ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:11.069984Z","caller":"traceutil/trace.go:171","msg":"trace[992290940] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:2737; }","duration":"213.493949ms","start":"2026-04-17T04:37:10.856473Z","end":"2026-04-17T04:37:11.069966Z","steps":["trace[992290940] 'agreement among raft nodes before linearized reading' (duration: 213.264654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.86363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:11.070051Z","caller":"traceutil/trace.go:171","msg":"trace[1472108474] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:2737; }","duration":"274.929912ms","start":"2026-04-17T04:37:10.795098Z","end":"2026-04-17T04:37:11.070028Z","steps":["trace[1472108474] 'agreement among raft nodes before linearized reading' (duration: 274.86184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.07008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.286872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/openvswitch-server\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-04-17T04:37:11.070118Z","caller":"traceutil/trace.go:171","msg":"trace[1545954691] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:1; response_revision:2737; }","duration":"244.346162ms","start":"2026-04-17T04:37:10.825763Z","end":"2026-04-17T04:37:11.070109Z","steps":["trace[1545954691] 'agreement among raft nodes before linearized reading' (duration: 244.280581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.082623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:11.070174Z","caller":"traceutil/trace.go:171","msg":"trace[1781841918] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:2737; }","duration":"266.371258ms","start":"2026-04-17T04:37:10.803788Z","end":"2026-04-17T04:37:11.07016Z","steps":["trace[1781841918] 'agreement among raft nodes before linearized reading' (duration: 266.106643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.069868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:10.767848Z","time spent":"302.007989ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":2,"response size":50091,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-17T04:37:11.070237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.853589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T04:37:11.070342Z","caller":"traceutil/trace.go:171","msg":"trace[1798401428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2737; }","duration":"183.965861ms","start":"2026-04-17T04:37:10.886366Z","end":"2026-04-17T04:37:11.070332Z","steps":["trace[1798401428] 'agreement among raft nodes before linearized reading' (duration: 183.775127ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:11.407976Z","caller":"traceutil/trace.go:171","msg":"trace[1396394409] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"335.022128ms","start":"2026-04-17T04:37:11.072916Z","end":"2026-04-17T04:37:11.407938Z","steps":["trace[1396394409] 'process raft request' (duration: 334.758113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.408211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.072904Z","time spent":"335.196511ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":107408,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:37:11.584031Z","caller":"traceutil/trace.go:171","msg":"trace[314321243] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2879; }","duration":"509.12741ms","start":"2026-04-17T04:37:11.074879Z","end":"2026-04-17T04:37:11.584006Z","steps":["trace[314321243] 'read index received' (duration: 332.765572ms)","trace[314321243] 'applied index is now lower than readState.Index' (duration: 176.360288ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:11.584032Z","caller":"traceutil/trace.go:171","msg":"trace[1871462341] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"509.457546ms","start":"2026-04-17T04:37:11.074534Z","end":"2026-04-17T04:37:11.583992Z","steps":["trace[1871462341] 'process raft request' (duration: 498.371321ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:11.584141Z","caller":"traceutil/trace.go:171","msg":"trace[72664396] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"508.12082ms","start":"2026-04-17T04:37:11.075977Z","end":"2026-04-17T04:37:11.584098Z","steps":["trace[72664396] 'process raft request' (duration: 507.965377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.584237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.074505Z","time spent":"509.62442ms","remote":"127.0.0.1:53450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":793,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:37:11.584249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.347054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:11.58429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.075967Z","time spent":"508.243192ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:37:11.584321Z","caller":"traceutil/trace.go:171","msg":"trace[849633754] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:2741; }","duration":"509.455616ms","start":"2026-04-17T04:37:11.074844Z","end":"2026-04-17T04:37:11.5843Z","steps":["trace[849633754] 'agreement among raft nodes before linearized reading' (duration: 509.319683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.58437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.074825Z","time spent":"509.532688ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/local-path-storage/local-path-config\" "} {"level":"info","ts":"2026-04-17T04:37:11.584787Z","caller":"traceutil/trace.go:171","msg":"trace[122180016] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"510.009167ms","start":"2026-04-17T04:37:11.074741Z","end":"2026-04-17T04:37:11.58475Z","steps":["trace[122180016] 'process raft request' (duration: 509.15501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.584958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.074734Z","time spent":"510.133789ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1268,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:37:11.597188Z","caller":"traceutil/trace.go:171","msg":"trace[369606648] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"187.102766ms","start":"2026-04-17T04:37:11.410027Z","end":"2026-04-17T04:37:11.59713Z","steps":["trace[369606648] 'process raft request' (duration: 186.82087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.412943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:11.597221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.969622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:11.597294Z","caller":"traceutil/trace.go:171","msg":"trace[685222879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"153.516806ms","start":"2026-04-17T04:37:11.443758Z","end":"2026-04-17T04:37:11.597275Z","steps":["trace[685222879] 'agreement among raft nodes before linearized reading' (duration: 153.413644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.147443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T04:37:11.59736Z","caller":"traceutil/trace.go:171","msg":"trace[1335979404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2742; }","duration":"131.233135ms","start":"2026-04-17T04:37:11.466113Z","end":"2026-04-17T04:37:11.597346Z","steps":["trace[1335979404] 'agreement among raft nodes before linearized reading' (duration: 131.046752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.173351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10239"} {"level":"info","ts":"2026-04-17T04:37:11.597406Z","caller":"traceutil/trace.go:171","msg":"trace[1878959182] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2742; }","duration":"336.240182ms","start":"2026-04-17T04:37:11.261158Z","end":"2026-04-17T04:37:11.597399Z","steps":["trace[1878959182] 'agreement among raft nodes before linearized reading' (duration: 336.180131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.261133Z","time spent":"336.293443ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10262,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-17T04:37:11.597315Z","caller":"traceutil/trace.go:171","msg":"trace[1529636442] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:2742; }","duration":"273.117494ms","start":"2026-04-17T04:37:11.324172Z","end":"2026-04-17T04:37:11.597289Z","steps":["trace[1529636442] 'agreement among raft nodes before linearized reading' (duration: 272.89295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.259082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:50068"} {"level":"info","ts":"2026-04-17T04:37:11.597654Z","caller":"traceutil/trace.go:171","msg":"trace[516703975] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:2742; }","duration":"512.328483ms","start":"2026-04-17T04:37:11.085313Z","end":"2026-04-17T04:37:11.597641Z","steps":["trace[516703975] 'agreement among raft nodes before linearized reading' (duration: 512.233261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.085287Z","time spent":"512.385805ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":2,"response size":50091,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-17T04:37:11.597809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.004511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:11.597835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.96099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10239"} {"level":"info","ts":"2026-04-17T04:37:11.597882Z","caller":"traceutil/trace.go:171","msg":"trace[782238053] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2742; }","duration":"440.033393ms","start":"2026-04-17T04:37:11.157842Z","end":"2026-04-17T04:37:11.597875Z","steps":["trace[782238053] 'agreement among raft nodes before linearized reading' (duration: 439.95871ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:11.597883Z","caller":"traceutil/trace.go:171","msg":"trace[430048444] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:2742; }","duration":"271.160616ms","start":"2026-04-17T04:37:11.326705Z","end":"2026-04-17T04:37:11.597866Z","steps":["trace[430048444] 'agreement among raft nodes before linearized reading' (duration: 270.364199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:11.597904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.157821Z","time spent":"440.077633ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10262,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-17T04:37:11.893845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.188373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:37:11.894017Z","caller":"traceutil/trace.go:171","msg":"trace[1643222748] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"291.238882ms","start":"2026-04-17T04:37:11.602747Z","end":"2026-04-17T04:37:11.893985Z","steps":["trace[1643222748] 'process raft request' (duration: 165.782214ms)","trace[1643222748] 'compare' (duration: 124.988769ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:12.118167Z","caller":"traceutil/trace.go:171","msg":"trace[1262073101] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"515.269304ms","start":"2026-04-17T04:37:11.60284Z","end":"2026-04-17T04:37:12.118109Z","steps":["trace[1262073101] 'process raft request' (duration: 514.983108ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:12.118228Z","caller":"traceutil/trace.go:171","msg":"trace[729678627] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2884; }","duration":"513.685872ms","start":"2026-04-17T04:37:11.604508Z","end":"2026-04-17T04:37:12.118194Z","steps":["trace[729678627] 'read index received' (duration: 164.13332ms)","trace[729678627] 'applied index is now lower than readState.Index' (duration: 349.551161ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:12.118398Z","caller":"traceutil/trace.go:171","msg":"trace[1754049438] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"515.196092ms","start":"2026-04-17T04:37:11.60319Z","end":"2026-04-17T04:37:12.118386Z","steps":["trace[1754049438] 'process raft request' (duration: 514.816275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.118407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.602815Z","time spent":"515.469987ms","remote":"127.0.0.1:53450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:37:12.118549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.603178Z","time spent":"515.264083ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1383,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:37:12.118597Z","caller":"traceutil/trace.go:171","msg":"trace[820109400] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"413.872992ms","start":"2026-04-17T04:37:11.704674Z","end":"2026-04-17T04:37:12.118547Z","steps":["trace[820109400] 'process raft request' (duration: 413.444913ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:12.118615Z","caller":"traceutil/trace.go:171","msg":"trace[637807638] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"513.13277ms","start":"2026-04-17T04:37:11.605424Z","end":"2026-04-17T04:37:12.118557Z","steps":["trace[637807638] 'process raft request' (duration: 512.64943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.118856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.605397Z","time spent":"513.314184ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4346,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:37:12.118871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.704616Z","time spent":"414.077275ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:37:12.118868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.586359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:12.118968Z","caller":"traceutil/trace.go:171","msg":"trace[1379913441] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:2747; }","duration":"512.742113ms","start":"2026-04-17T04:37:11.606201Z","end":"2026-04-17T04:37:12.118943Z","steps":["trace[1379913441] 'agreement among raft nodes before linearized reading' (duration: 512.525138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.119008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.606187Z","time spent":"512.812725ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-04-17T04:37:12.119027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.500539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:12.119116Z","caller":"traceutil/trace.go:171","msg":"trace[152218935] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:0; response_revision:2747; }","duration":"514.61432ms","start":"2026-04-17T04:37:11.604471Z","end":"2026-04-17T04:37:12.119085Z","steps":["trace[152218935] 'agreement among raft nodes before linearized reading' (duration: 514.464167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.119127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.295064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:12.119159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.604443Z","time spent":"514.707143ms","remote":"127.0.0.1:53648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/local-path-provisioner\" "} {"level":"info","ts":"2026-04-17T04:37:12.119198Z","caller":"traceutil/trace.go:171","msg":"trace[492990857] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:2747; }","duration":"510.398546ms","start":"2026-04-17T04:37:11.60878Z","end":"2026-04-17T04:37:12.119179Z","steps":["trace[492990857] 'agreement among raft nodes before linearized reading' (duration: 510.289084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.119037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.547267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10239"} {"level":"warn","ts":"2026-04-17T04:37:12.119232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:11.608767Z","time spent":"510.456317ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"info","ts":"2026-04-17T04:37:12.119279Z","caller":"traceutil/trace.go:171","msg":"trace[1763095028] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2747; }","duration":"284.829433ms","start":"2026-04-17T04:37:11.834428Z","end":"2026-04-17T04:37:12.119257Z","steps":["trace[1763095028] 'agreement among raft nodes before linearized reading' (duration: 284.509616ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:12.84677Z","caller":"traceutil/trace.go:171","msg":"trace[228747147] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2938; }","duration":"316.33259ms","start":"2026-04-17T04:37:12.530413Z","end":"2026-04-17T04:37:12.846746Z","steps":["trace[228747147] 'read index received' (duration: 225.278968ms)","trace[228747147] 'applied index is now lower than readState.Index' (duration: 91.052802ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:12.84681Z","caller":"traceutil/trace.go:171","msg":"trace[1354397929] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2793; }","duration":"316.735628ms","start":"2026-04-17T04:37:12.530043Z","end":"2026-04-17T04:37:12.846778Z","steps":["trace[1354397929] 'process raft request' (duration: 225.626855ms)","trace[1354397929] 'compare' (duration: 91.018851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:37:12.846938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:12.530025Z","time spent":"316.85086ms","remote":"127.0.0.1:53400","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:37:12.846965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.969441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-17T04:37:12.847003Z","caller":"traceutil/trace.go:171","msg":"trace[1026201717] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:2793; }","duration":"310.050812ms","start":"2026-04-17T04:37:12.536942Z","end":"2026-04-17T04:37:12.846993Z","steps":["trace[1026201717] 'agreement among raft nodes before linearized reading' (duration: 309.94273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.846953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.502623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:12.847029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:12.536933Z","time spent":"310.090222ms","remote":"127.0.0.1:53486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-04-17T04:37:12.847061Z","caller":"traceutil/trace.go:171","msg":"trace[2019384813] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io; range_end:; response_count:0; response_revision:2793; }","duration":"316.644336ms","start":"2026-04-17T04:37:12.53039Z","end":"2026-04-17T04:37:12.847034Z","steps":["trace[2019384813] 'agreement among raft nodes before linearized reading' (duration: 316.448372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:12.847112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:12.530383Z","time spent":"316.720497ms","remote":"127.0.0.1:53334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-17T04:37:12.847113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.350622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-17T04:37:12.84716Z","caller":"traceutil/trace.go:171","msg":"trace[1064870223] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:2793; }","duration":"280.452264ms","start":"2026-04-17T04:37:12.566696Z","end":"2026-04-17T04:37:12.847148Z","steps":["trace[1064870223] 'agreement among raft nodes before linearized reading' (duration: 280.27587ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.01021Z","caller":"traceutil/trace.go:171","msg":"trace[432155483] linearizableReadLoop","detail":"{readStateIndex:2940; appliedIndex:2939; }","duration":"153.184559ms","start":"2026-04-17T04:37:12.857005Z","end":"2026-04-17T04:37:13.010189Z","steps":["trace[432155483] 'read index received' (duration: 153.010705ms)","trace[432155483] 'applied index is now lower than readState.Index' (duration: 172.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:13.010339Z","caller":"traceutil/trace.go:171","msg":"trace[1232517233] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"154.587847ms","start":"2026-04-17T04:37:12.855742Z","end":"2026-04-17T04:37:13.01033Z","steps":["trace[1232517233] 'process raft request' (duration: 154.308531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.010532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.516376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectstores.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.010568Z","caller":"traceutil/trace.go:171","msg":"trace[1399927564] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectstores.ceph.rook.io; range_end:; response_count:0; response_revision:2794; }","duration":"153.617088ms","start":"2026-04-17T04:37:12.85694Z","end":"2026-04-17T04:37:13.010557Z","steps":["trace[1399927564] 'agreement among raft nodes before linearized reading' (duration: 153.514806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.51431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.030506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:37:13.514633Z","caller":"traceutil/trace.go:171","msg":"trace[1221623672] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"491.952932ms","start":"2026-04-17T04:37:13.02265Z","end":"2026-04-17T04:37:13.514603Z","steps":["trace[1221623672] 'process raft request' (duration: 250.494007ms)","trace[1221623672] 'compare' (duration: 240.861553ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:13.514736Z","caller":"traceutil/trace.go:171","msg":"trace[276772212] linearizableReadLoop","detail":"{readStateIndex:2942; appliedIndex:2941; }","duration":"428.728983ms","start":"2026-04-17T04:37:13.085957Z","end":"2026-04-17T04:37:13.514686Z","steps":["trace[276772212] 'read index received' (duration: 187.122166ms)","trace[276772212] 'applied index is now lower than readState.Index' (duration: 241.601017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:37:13.514824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.022619Z","time spent":"492.112615ms","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:37:13.514795Z","caller":"traceutil/trace.go:171","msg":"trace[1584084257] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"423.502567ms","start":"2026-04-17T04:37:13.091248Z","end":"2026-04-17T04:37:13.51475Z","steps":["trace[1584084257] 'process raft request' (duration: 423.296103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.514931Z","caller":"traceutil/trace.go:171","msg":"trace[1546508917] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"261.409608ms","start":"2026-04-17T04:37:13.253502Z","end":"2026-04-17T04:37:13.514912Z","steps":["trace[1546508917] 'process raft request' (duration: 261.177813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.514817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.978381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:13.514992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.091221Z","time spent":"423.674561ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:37:13.515023Z","caller":"traceutil/trace.go:171","msg":"trace[945079519] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:2797; }","duration":"483.226726ms","start":"2026-04-17T04:37:13.031767Z","end":"2026-04-17T04:37:13.514994Z","steps":["trace[945079519] 'agreement among raft nodes before linearized reading' (duration: 482.9298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.515064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.410683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectzonegroups.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.515122Z","caller":"traceutil/trace.go:171","msg":"trace[1980563398] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectzonegroups.ceph.rook.io; range_end:; response_count:0; response_revision:2798; }","duration":"497.558136ms","start":"2026-04-17T04:37:13.017547Z","end":"2026-04-17T04:37:13.515105Z","steps":["trace[1980563398] 'agreement among raft nodes before linearized reading' (duration: 497.349592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.515125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.031747Z","time spent":"483.341968ms","remote":"127.0.0.1:53486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" "} {"level":"warn","ts":"2026-04-17T04:37:13.515171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.017522Z","time spent":"497.639268ms","remote":"127.0.0.1:53334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectzonegroups.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-17T04:37:13.607311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.985977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.607413Z","caller":"traceutil/trace.go:171","msg":"trace[1361424950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2799; }","duration":"291.15985ms","start":"2026-04-17T04:37:13.316231Z","end":"2026-04-17T04:37:13.607391Z","steps":["trace[1361424950] 'agreement among raft nodes before linearized reading' (duration: 290.990456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.607434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.081619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.607418Z","caller":"traceutil/trace.go:171","msg":"trace[1951731011] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:2799; }","duration":"100.044245ms","start":"2026-04-17T04:37:13.507335Z","end":"2026-04-17T04:37:13.607379Z","steps":["trace[1951731011] 'agreement among raft nodes before linearized reading' (duration: 99.971083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.607428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.697242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.607523Z","caller":"traceutil/trace.go:171","msg":"trace[425779265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2799; }","duration":"165.204862ms","start":"2026-04-17T04:37:13.44229Z","end":"2026-04-17T04:37:13.607495Z","steps":["trace[425779265] 'agreement among raft nodes before linearized reading' (duration: 165.083109ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.60762Z","caller":"traceutil/trace.go:171","msg":"trace[1441029140] range","detail":"{range_begin:/registry/configmaps/monitoring/node-feature-discovery-master-conf; range_end:; response_count:0; response_revision:2799; }","duration":"517.929968ms","start":"2026-04-17T04:37:13.089614Z","end":"2026-04-17T04:37:13.607544Z","steps":["trace[1441029140] 'agreement among raft nodes before linearized reading' (duration: 517.566191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.607286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.20391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.607772Z","caller":"traceutil/trace.go:171","msg":"trace[1871498750] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2799; }","duration":"201.790182ms","start":"2026-04-17T04:37:13.40596Z","end":"2026-04-17T04:37:13.607751Z","steps":["trace[1871498750] 'agreement among raft nodes before linearized reading' (duration: 201.129759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.607315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.219163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:37:13.608023Z","caller":"traceutil/trace.go:171","msg":"trace[1454727150] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2799; }","duration":"386.009569ms","start":"2026-04-17T04:37:13.221999Z","end":"2026-04-17T04:37:13.608009Z","steps":["trace[1454727150] 'agreement among raft nodes before linearized reading' (duration: 385.127022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.608089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.221969Z","time spent":"386.090331ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":22,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:37:13.607688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:37:13.089586Z","time spent":"518.09007ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" "} {"level":"info","ts":"2026-04-17T04:37:13.607299Z","caller":"traceutil/trace.go:171","msg":"trace[933052216] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"107.645038ms","start":"2026-04-17T04:37:13.499622Z","end":"2026-04-17T04:37:13.607267Z","steps":["trace[933052216] 'process raft request' (duration: 107.20185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.607209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.540812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.608826Z","caller":"traceutil/trace.go:171","msg":"trace[874791353] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2799; }","duration":"221.265086ms","start":"2026-04-17T04:37:13.387542Z","end":"2026-04-17T04:37:13.608807Z","steps":["trace[874791353] 'agreement among raft nodes before linearized reading' (duration: 219.538522ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.717963Z","caller":"traceutil/trace.go:171","msg":"trace[1563296670] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"103.360301ms","start":"2026-04-17T04:37:13.61457Z","end":"2026-04-17T04:37:13.717931Z","steps":["trace[1563296670] 'process raft request' (duration: 102.952733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.718208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.951672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.717876Z","caller":"traceutil/trace.go:171","msg":"trace[1373742568] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"102.614336ms","start":"2026-04-17T04:37:13.615218Z","end":"2026-04-17T04:37:13.717833Z","steps":["trace[1373742568] 'read index received' (duration: 102.435402ms)","trace[1373742568] 'applied index is now lower than readState.Index' (duration: 177.263µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:13.718356Z","caller":"traceutil/trace.go:171","msg":"trace[109876386] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-tcp; range_end:; response_count:0; response_revision:2801; }","duration":"103.136566ms","start":"2026-04-17T04:37:13.615163Z","end":"2026-04-17T04:37:13.718299Z","steps":["trace[109876386] 'agreement among raft nodes before linearized reading' (duration: 102.898991ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.831532Z","caller":"traceutil/trace.go:171","msg":"trace[143552998] linearizableReadLoop","detail":"{readStateIndex:2949; appliedIndex:2947; }","duration":"113.18425ms","start":"2026-04-17T04:37:13.718329Z","end":"2026-04-17T04:37:13.831513Z","steps":["trace[143552998] 'read index received' (duration: 112.917845ms)","trace[143552998] 'applied index is now lower than readState.Index' (duration: 265.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:37:13.831646Z","caller":"traceutil/trace.go:171","msg":"trace[1742318214] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"213.199843ms","start":"2026-04-17T04:37:13.618436Z","end":"2026-04-17T04:37:13.831636Z","steps":["trace[1742318214] 'process raft request' (duration: 212.870156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.831809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.25477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:13.831816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.356087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephrbdmirrors.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.831875Z","caller":"traceutil/trace.go:171","msg":"trace[607812813] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-udp; range_end:; response_count:0; response_revision:2803; }","duration":"109.367822ms","start":"2026-04-17T04:37:13.722489Z","end":"2026-04-17T04:37:13.831857Z","steps":["trace[607812813] 'agreement among raft nodes before linearized reading' (duration: 109.196629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.831857Z","caller":"traceutil/trace.go:171","msg":"trace[1982515446] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"212.997668ms","start":"2026-04-17T04:37:13.618815Z","end":"2026-04-17T04:37:13.831813Z","steps":["trace[1982515446] 'process raft request' (duration: 212.656742ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.831907Z","caller":"traceutil/trace.go:171","msg":"trace[2029015624] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephrbdmirrors.ceph.rook.io; range_end:; response_count:0; response_revision:2803; }","duration":"215.818446ms","start":"2026-04-17T04:37:13.616064Z","end":"2026-04-17T04:37:13.831882Z","steps":["trace[2029015624] 'agreement among raft nodes before linearized reading' (duration: 215.588881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:37:13.832009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.736724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/node-feature-discovery-topology-updater-conf\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:37:13.831981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.727364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:37:13.832044Z","caller":"traceutil/trace.go:171","msg":"trace[987878053] range","detail":"{range_begin:/registry/configmaps/monitoring/node-feature-discovery-topology-updater-conf; range_end:; response_count:0; response_revision:2803; }","duration":"216.809136ms","start":"2026-04-17T04:37:13.615224Z","end":"2026-04-17T04:37:13.832033Z","steps":["trace[987878053] 'agreement among raft nodes before linearized reading' (duration: 216.738774ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:37:13.832099Z","caller":"traceutil/trace.go:171","msg":"trace[488980000] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:0; response_revision:2803; }","duration":"214.950399ms","start":"2026-04-17T04:37:13.61712Z","end":"2026-04-17T04:37:13.83207Z","steps":["trace[488980000] 'agreement among raft nodes before linearized reading' (duration: 214.702504ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:09.279502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2680} {"level":"info","ts":"2026-04-17T04:42:09.323129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2680,"took":"42.094177ms","hash":3562055959,"current-db-size-bytes":23859200,"current-db-size":"24 MB","current-db-size-in-use-bytes":23379968,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-17T04:42:09.323206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3562055959,"revision":2680,"compact-revision":1706} {"level":"info","ts":"2026-04-17T04:46:27.750353Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002447.wal"} {"level":"info","ts":"2026-04-17T04:46:54.077982Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T04:46:54.085006Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T04:46:54.085194Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T04:47:09.288165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5837} {"level":"info","ts":"2026-04-17T04:47:09.382513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5837,"took":"91.978746ms","hash":141304794,"current-db-size-bytes":55463936,"current-db-size":"56 MB","current-db-size-in-use-bytes":41672704,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-17T04:47:09.382633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":141304794,"revision":5837,"compact-revision":2680} {"level":"info","ts":"2026-04-17T04:47:54.895369Z","caller":"traceutil/trace.go:171","msg":"trace[973620988] linearizableReadLoop","detail":"{readStateIndex:10968; appliedIndex:10967; }","duration":"165.105852ms","start":"2026-04-17T04:47:54.730233Z","end":"2026-04-17T04:47:54.895339Z","steps":["trace[973620988] 'read index received' (duration: 164.876936ms)","trace[973620988] 'applied index is now lower than readState.Index' (duration: 228.176µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:47:54.895504Z","caller":"traceutil/trace.go:171","msg":"trace[334783996] transaction","detail":"{read_only:false; response_revision:10595; number_of_response:1; }","duration":"191.782301ms","start":"2026-04-17T04:47:54.70371Z","end":"2026-04-17T04:47:54.895492Z","steps":["trace[334783996] 'process raft request' (duration: 191.467445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:47:54.895726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.468249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:47:54.895779Z","caller":"traceutil/trace.go:171","msg":"trace[1018353466] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10595; }","duration":"165.573341ms","start":"2026-04-17T04:47:54.730191Z","end":"2026-04-17T04:47:54.895764Z","steps":["trace[1018353466] 'agreement among raft nodes before linearized reading' (duration: 165.457539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:47:54.895818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.768418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:47:54.895988Z","caller":"traceutil/trace.go:171","msg":"trace[1551021664] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:10595; }","duration":"126.006523ms","start":"2026-04-17T04:47:54.769955Z","end":"2026-04-17T04:47:54.895962Z","steps":["trace[1551021664] 'agreement among raft nodes before linearized reading' (duration: 125.720237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:47:54.895989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.863717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-service\" ","response":"range_response_count:1 size:6341"} {"level":"info","ts":"2026-04-17T04:47:54.89615Z","caller":"traceutil/trace.go:171","msg":"trace[207838297] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-service; range_end:; response_count:1; response_revision:10595; }","duration":"164.146983ms","start":"2026-04-17T04:47:54.731984Z","end":"2026-04-17T04:47:54.896131Z","steps":["trace[207838297] 'agreement among raft nodes before linearized reading' (duration: 163.737894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:47:54.895984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.99369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-17T04:47:54.896207Z","caller":"traceutil/trace.go:171","msg":"trace[1647012321] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:10595; }","duration":"164.286736ms","start":"2026-04-17T04:47:54.731907Z","end":"2026-04-17T04:47:54.896194Z","steps":["trace[1647012321] 'agreement among raft nodes before linearized reading' (duration: 163.739835ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:09.293156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9943} {"level":"info","ts":"2026-04-17T04:52:09.411206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9943,"took":"115.102993ms","hash":1297090290,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":34324480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T04:52:09.411266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297090290,"revision":9943,"compact-revision":5837} {"level":"info","ts":"2026-04-17T04:52:23.658678Z","caller":"traceutil/trace.go:171","msg":"trace[1274614498] transaction","detail":"{read_only:false; response_revision:13262; number_of_response:1; }","duration":"168.397478ms","start":"2026-04-17T04:52:23.490253Z","end":"2026-04-17T04:52:23.658651Z","steps":["trace[1274614498] 'process raft request' (duration: 111.232674ms)","trace[1274614498] 'compare' (duration: 57.050792ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:23.860341Z","caller":"traceutil/trace.go:171","msg":"trace[1577557933] linearizableReadLoop","detail":"{readStateIndex:13712; appliedIndex:13711; }","duration":"185.744519ms","start":"2026-04-17T04:52:23.674568Z","end":"2026-04-17T04:52:23.860312Z","steps":["trace[1577557933] 'read index received' (duration: 101.925168ms)","trace[1577557933] 'applied index is now lower than readState.Index' (duration: 83.818351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:52:23.860529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.937712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/rook-ceph/csi-rbdplugin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:23.860573Z","caller":"traceutil/trace.go:171","msg":"trace[1787969969] range","detail":"{range_begin:/registry/daemonsets/rook-ceph/csi-rbdplugin; range_end:; response_count:0; response_revision:13264; }","duration":"186.010143ms","start":"2026-04-17T04:52:23.674548Z","end":"2026-04-17T04:52:23.860558Z","steps":["trace[1787969969] 'agreement among raft nodes before linearized reading' (duration: 185.868291ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:23.860773Z","caller":"traceutil/trace.go:171","msg":"trace[941123535] transaction","detail":"{read_only:false; response_revision:13264; number_of_response:1; }","duration":"190.045654ms","start":"2026-04-17T04:52:23.670667Z","end":"2026-04-17T04:52:23.860713Z","steps":["trace[941123535] 'process raft request' (duration: 105.885477ms)","trace[941123535] 'compare' (duration: 83.495564ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:25.318486Z","caller":"traceutil/trace.go:171","msg":"trace[201640316] transaction","detail":"{read_only:false; response_revision:13306; number_of_response:1; }","duration":"248.290009ms","start":"2026-04-17T04:52:25.070151Z","end":"2026-04-17T04:52:25.318441Z","steps":["trace[201640316] 'process raft request' (duration: 160.08356ms)","trace[201640316] 'compare' (duration: 87.990245ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:25.343437Z","caller":"traceutil/trace.go:171","msg":"trace[312680992] linearizableReadLoop","detail":"{readStateIndex:13755; appliedIndex:13753; }","duration":"253.144857ms","start":"2026-04-17T04:52:25.090267Z","end":"2026-04-17T04:52:25.343412Z","steps":["trace[312680992] 'read index received' (duration: 139.920573ms)","trace[312680992] 'applied index is now lower than readState.Index' (duration: 113.223164ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:25.343534Z","caller":"traceutil/trace.go:171","msg":"trace[439417775] transaction","detail":"{read_only:false; response_revision:13307; number_of_response:1; }","duration":"254.861772ms","start":"2026-04-17T04:52:25.088635Z","end":"2026-04-17T04:52:25.343497Z","steps":["trace[439417775] 'process raft request' (duration: 254.619507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:25.343691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.374851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:52:25.343728Z","caller":"traceutil/trace.go:171","msg":"trace[1159694033] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:13307; }","duration":"253.482974ms","start":"2026-04-17T04:52:25.090233Z","end":"2026-04-17T04:52:25.343716Z","steps":["trace[1159694033] 'agreement among raft nodes before linearized reading' (duration: 253.331851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:25.343874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.058214ms","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-04-17T04:52:25.34392Z","caller":"traceutil/trace.go:171","msg":"trace[1788816311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13307; }","duration":"188.138366ms","start":"2026-04-17T04:52:25.155759Z","end":"2026-04-17T04:52:25.343897Z","steps":["trace[1788816311] 'agreement among raft nodes before linearized reading' (duration: 187.971532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:25.344085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.39761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T04:52:25.344117Z","caller":"traceutil/trace.go:171","msg":"trace[1816491163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13307; }","duration":"185.447752ms","start":"2026-04-17T04:52:25.158661Z","end":"2026-04-17T04:52:25.344109Z","steps":["trace[1816491163] 'agreement among raft nodes before linearized reading' (duration: 185.36031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:25.344097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.077564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:25.344191Z","caller":"traceutil/trace.go:171","msg":"trace[797203565] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:13307; }","duration":"182.205156ms","start":"2026-04-17T04:52:25.161963Z","end":"2026-04-17T04:52:25.344168Z","steps":["trace[797203565] 'agreement among raft nodes before linearized reading' (duration: 182.015302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.115171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.391372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:52:29.115254Z","caller":"traceutil/trace.go:171","msg":"trace[1201495585] linearizableReadLoop","detail":"{readStateIndex:14060; appliedIndex:14059; }","duration":"216.666112ms","start":"2026-04-17T04:52:28.898574Z","end":"2026-04-17T04:52:29.11524Z","steps":["trace[1201495585] 'read index received' (duration: 23.050155ms)","trace[1201495585] 'applied index is now lower than readState.Index' (duration: 193.615087ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:29.115365Z","caller":"traceutil/trace.go:171","msg":"trace[1910632857] transaction","detail":"{read_only:false; response_revision:13605; number_of_response:1; }","duration":"217.176212ms","start":"2026-04-17T04:52:28.898166Z","end":"2026-04-17T04:52:29.115342Z","steps":["trace[1910632857] 'process raft request' (duration: 23.528674ms)","trace[1910632857] 'compare' (duration: 193.189458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:52:29.115468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.863146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-csi-rbd-node\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:29.115561Z","caller":"traceutil/trace.go:171","msg":"trace[231168933] range","detail":"{range_begin:/registry/secrets/openstack/rook-csi-rbd-node; range_end:; response_count:0; response_revision:13605; }","duration":"217.005218ms","start":"2026-04-17T04:52:28.898533Z","end":"2026-04-17T04:52:29.115539Z","steps":["trace[231168933] 'agreement among raft nodes before linearized reading' (duration: 216.786004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:29.187215Z","caller":"traceutil/trace.go:171","msg":"trace[1296526823] transaction","detail":"{read_only:false; response_revision:13606; number_of_response:1; }","duration":"285.708954ms","start":"2026-04-17T04:52:28.901485Z","end":"2026-04-17T04:52:29.187194Z","steps":["trace[1296526823] 'process raft request' (duration: 285.598772ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:29.246545Z","caller":"traceutil/trace.go:171","msg":"trace[2095693629] transaction","detail":"{read_only:false; response_revision:13608; number_of_response:1; }","duration":"156.340673ms","start":"2026-04-17T04:52:29.090175Z","end":"2026-04-17T04:52:29.246516Z","steps":["trace[2095693629] 'process raft request' (duration: 156.216391ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:29.246597Z","caller":"traceutil/trace.go:171","msg":"trace[2067845237] transaction","detail":"{read_only:false; response_revision:13607; number_of_response:1; }","duration":"261.21512ms","start":"2026-04-17T04:52:28.985331Z","end":"2026-04-17T04:52:29.246546Z","steps":["trace[2067845237] 'process raft request' (duration: 260.863743ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:29.246806Z","caller":"traceutil/trace.go:171","msg":"trace[1018796280] transaction","detail":"{read_only:false; response_revision:13609; number_of_response:1; }","duration":"142.891692ms","start":"2026-04-17T04:52:29.103893Z","end":"2026-04-17T04:52:29.246785Z","steps":["trace[1018796280] 'process raft request' (duration: 142.576186ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:29.247141Z","caller":"traceutil/trace.go:171","msg":"trace[1690049151] linearizableReadLoop","detail":"{readStateIndex:14064; appliedIndex:14061; }","duration":"131.86394ms","start":"2026-04-17T04:52:29.115268Z","end":"2026-04-17T04:52:29.247132Z","steps":["trace[1690049151] 'read index received' (duration: 71.91189ms)","trace[1690049151] 'applied index is now lower than readState.Index' (duration: 59.95123ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:29.247203Z","caller":"traceutil/trace.go:171","msg":"trace[668605286] transaction","detail":"{read_only:false; response_revision:13610; number_of_response:1; }","duration":"127.798649ms","start":"2026-04-17T04:52:29.119395Z","end":"2026-04-17T04:52:29.247194Z","steps":["trace[668605286] 'process raft request' (duration: 127.310218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.247395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.164443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-17T04:52:29.247419Z","caller":"traceutil/trace.go:171","msg":"trace[2060410770] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:13610; }","duration":"337.259265ms","start":"2026-04-17T04:52:28.910152Z","end":"2026-04-17T04:52:29.247411Z","steps":["trace[2060410770] 'agreement among raft nodes before linearized reading' (duration: 337.106452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.24743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.465057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"warn","ts":"2026-04-17T04:52:29.24744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:52:28.910131Z","time spent":"337.303736ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-04-17T04:52:29.247479Z","caller":"traceutil/trace.go:171","msg":"trace[1795882444] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:13610; }","duration":"322.580088ms","start":"2026-04-17T04:52:28.924883Z","end":"2026-04-17T04:52:29.247463Z","steps":["trace[1795882444] 'agreement among raft nodes before linearized reading' (duration: 322.344253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.247522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:52:28.924861Z","time spent":"322.6525ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3588,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-17T04:52:29.247656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.459184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:52:29.247689Z","caller":"traceutil/trace.go:171","msg":"trace[1312774371] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:13610; }","duration":"303.511084ms","start":"2026-04-17T04:52:28.944169Z","end":"2026-04-17T04:52:29.24768Z","steps":["trace[1312774371] 'agreement among raft nodes before linearized reading' (duration: 303.431373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.247713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:52:28.944161Z","time spent":"303.546315ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:52:29.253172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.099715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/nova-novncproxy-55779d6d65-2gqcb.18a70bcbef877464\" ","response":"range_response_count:1 size:821"} {"level":"info","ts":"2026-04-17T04:52:29.253237Z","caller":"traceutil/trace.go:171","msg":"trace[1930686686] range","detail":"{range_begin:/registry/events/openstack/nova-novncproxy-55779d6d65-2gqcb.18a70bcbef877464; range_end:; response_count:1; response_revision:13612; }","duration":"133.205858ms","start":"2026-04-17T04:52:29.120014Z","end":"2026-04-17T04:52:29.25322Z","steps":["trace[1930686686] 'agreement among raft nodes before linearized reading' (duration: 132.976013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:29.253762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.239547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:29.253796Z","caller":"traceutil/trace.go:171","msg":"trace[165835319] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:13612; }","duration":"129.306299ms","start":"2026-04-17T04:52:29.12448Z","end":"2026-04-17T04:52:29.253786Z","steps":["trace[165835319] 'agreement among raft nodes before linearized reading' (duration: 129.247528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:33.53265Z","caller":"traceutil/trace.go:171","msg":"trace[784920472] transaction","detail":"{read_only:false; response_revision:13849; number_of_response:1; }","duration":"117.122242ms","start":"2026-04-17T04:52:33.415482Z","end":"2026-04-17T04:52:33.532604Z","steps":["trace[784920472] 'process raft request' (duration: 49.719403ms)","trace[784920472] 'compare' (duration: 67.205935ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:33.535411Z","caller":"traceutil/trace.go:171","msg":"trace[738639734] transaction","detail":"{read_only:false; response_revision:13850; number_of_response:1; }","duration":"116.707103ms","start":"2026-04-17T04:52:33.418632Z","end":"2026-04-17T04:52:33.535339Z","steps":["trace[738639734] 'process raft request' (duration: 116.570551ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:33.535459Z","caller":"traceutil/trace.go:171","msg":"trace[39356341] linearizableReadLoop","detail":"{readStateIndex:14313; appliedIndex:14310; }","duration":"113.700623ms","start":"2026-04-17T04:52:33.42174Z","end":"2026-04-17T04:52:33.535441Z","steps":["trace[39356341] 'read index received' (duration: 43.346474ms)","trace[39356341] 'applied index is now lower than readState.Index' (duration: 70.352969ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:52:33.535598Z","caller":"traceutil/trace.go:171","msg":"trace[455986843] transaction","detail":"{read_only:false; response_revision:13851; number_of_response:1; }","duration":"116.675893ms","start":"2026-04-17T04:52:33.418888Z","end":"2026-04-17T04:52:33.535564Z","steps":["trace[455986843] 'process raft request' (duration: 116.397167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.535749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.109212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:33.535786Z","caller":"traceutil/trace.go:171","msg":"trace[2144737928] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:13852; }","duration":"112.176804ms","start":"2026-04-17T04:52:33.423598Z","end":"2026-04-17T04:52:33.535775Z","steps":["trace[2144737928] 'agreement among raft nodes before linearized reading' (duration: 112.090641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.535909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.949739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:33.535973Z","caller":"traceutil/trace.go:171","msg":"trace[665494468] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13852; }","duration":"112.02781ms","start":"2026-04-17T04:52:33.423935Z","end":"2026-04-17T04:52:33.535963Z","steps":["trace[665494468] 'agreement among raft nodes before linearized reading' (duration: 111.931388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.536082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.318987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T04:52:33.536129Z","caller":"traceutil/trace.go:171","msg":"trace[1481614773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13852; }","duration":"114.411369ms","start":"2026-04-17T04:52:33.421703Z","end":"2026-04-17T04:52:33.536114Z","steps":["trace[1481614773] 'agreement among raft nodes before linearized reading' (duration: 114.302607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.53622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.370287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-17T04:52:33.536256Z","caller":"traceutil/trace.go:171","msg":"trace[231581403] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13852; }","duration":"112.412637ms","start":"2026-04-17T04:52:33.423835Z","end":"2026-04-17T04:52:33.536247Z","steps":["trace[231581403] 'agreement among raft nodes before linearized reading' (duration: 112.192653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.536286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.157813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-system/capi-controller-manager\" ","response":"range_response_count:1 size:4521"} {"level":"info","ts":"2026-04-17T04:52:33.536324Z","caller":"traceutil/trace.go:171","msg":"trace[57783681] range","detail":"{range_begin:/registry/deployments/capi-system/capi-controller-manager; range_end:; response_count:1; response_revision:13852; }","duration":"111.200144ms","start":"2026-04-17T04:52:33.425113Z","end":"2026-04-17T04:52:33.536313Z","steps":["trace[57783681] 'agreement among raft nodes before linearized reading' (duration: 111.099042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.536607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.770814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:52:33.536638Z","caller":"traceutil/trace.go:171","msg":"trace[2071878972] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13852; }","duration":"112.810216ms","start":"2026-04-17T04:52:33.42382Z","end":"2026-04-17T04:52:33.536631Z","steps":["trace[2071878972] 'agreement among raft nodes before linearized reading' (duration: 112.756545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:52:33.536766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.956779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-04-17T04:52:33.53679Z","caller":"traceutil/trace.go:171","msg":"trace[257780720] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13852; }","duration":"112.987719ms","start":"2026-04-17T04:52:33.423795Z","end":"2026-04-17T04:52:33.536783Z","steps":["trace[257780720] 'agreement among raft nodes before linearized reading' (duration: 112.920488ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:57:09.300243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13109} {"level":"info","ts":"2026-04-17T04:57:09.439819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13109,"took":"125.718035ms","hash":3341355015,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":45879296,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-04-17T04:57:09.439883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3341355015,"revision":13109,"compact-revision":9943} {"level":"info","ts":"2026-04-17T05:00:16.009778Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T05:00:16.02865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-17T05:00:16.028814Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-17T05:01:32.839706Z","caller":"traceutil/trace.go:171","msg":"trace[870939858] transaction","detail":"{read_only:false; response_revision:20413; number_of_response:1; }","duration":"105.204832ms","start":"2026-04-17T05:01:32.734454Z","end":"2026-04-17T05:01:32.839658Z","steps":["trace[870939858] 'process raft request' (duration: 104.723422ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:07.018664Z","caller":"traceutil/trace.go:171","msg":"trace[1792652500] transaction","detail":"{read_only:false; response_revision:20696; number_of_response:1; }","duration":"151.140724ms","start":"2026-04-17T05:02:06.867493Z","end":"2026-04-17T05:02:07.018634Z","steps":["trace[1792652500] 'process raft request' (duration: 130.356347ms)","trace[1792652500] 'compare' (duration: 19.789817ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:02:07.018976Z","caller":"traceutil/trace.go:171","msg":"trace[373914297] transaction","detail":"{read_only:false; response_revision:20697; number_of_response:1; }","duration":"151.374979ms","start":"2026-04-17T05:02:06.86757Z","end":"2026-04-17T05:02:07.018945Z","steps":["trace[373914297] 'process raft request' (duration: 150.555683ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:09.306103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17550} {"level":"info","ts":"2026-04-17T05:02:09.466629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17550,"took":"156.264777ms","hash":3789813932,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":35823616,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-17T05:02:09.466757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3789813932,"revision":17550,"compact-revision":13109} {"level":"info","ts":"2026-04-17T05:02:14.214047Z","caller":"traceutil/trace.go:171","msg":"trace[1830010488] transaction","detail":"{read_only:false; response_revision:20775; number_of_response:1; }","duration":"129.314338ms","start":"2026-04-17T05:02:14.084701Z","end":"2026-04-17T05:02:14.214016Z","steps":["trace[1830010488] 'process raft request' (duration: 129.104523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:14.219067Z","caller":"traceutil/trace.go:171","msg":"trace[1914109420] transaction","detail":"{read_only:false; response_revision:20777; number_of_response:1; }","duration":"117.914327ms","start":"2026-04-17T05:02:14.101142Z","end":"2026-04-17T05:02:14.219056Z","steps":["trace[1914109420] 'process raft request' (duration: 117.880916ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:14.219284Z","caller":"traceutil/trace.go:171","msg":"trace[537037761] transaction","detail":"{read_only:false; response_revision:20776; number_of_response:1; }","duration":"131.303885ms","start":"2026-04-17T05:02:14.087968Z","end":"2026-04-17T05:02:14.219272Z","steps":["trace[537037761] 'process raft request' (duration: 130.999999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:02:14.641755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.692512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T05:02:14.642002Z","caller":"traceutil/trace.go:171","msg":"trace[1389995623] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:20778; }","duration":"116.967328ms","start":"2026-04-17T05:02:14.525009Z","end":"2026-04-17T05:02:14.641976Z","steps":["trace[1389995623] 'count revisions from in-memory index tree' (duration: 116.641911ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:14.976949Z","caller":"traceutil/trace.go:171","msg":"trace[1610069980] transaction","detail":"{read_only:false; response_revision:20780; number_of_response:1; }","duration":"143.272676ms","start":"2026-04-17T05:02:14.833637Z","end":"2026-04-17T05:02:14.97691Z","steps":["trace[1610069980] 'process raft request' (duration: 143.050222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:02:15.20106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.842258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T05:02:15.201263Z","caller":"traceutil/trace.go:171","msg":"trace[369744858] linearizableReadLoop","detail":"{readStateIndex:21414; appliedIndex:21413; }","duration":"136.157954ms","start":"2026-04-17T05:02:15.065078Z","end":"2026-04-17T05:02:15.201236Z","steps":["trace[369744858] 'read index received' (duration: 60.681µs)","trace[369744858] 'applied index is now lower than readState.Index' (duration: 136.095483ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:02:15.201378Z","caller":"traceutil/trace.go:171","msg":"trace[2079772916] transaction","detail":"{read_only:false; response_revision:20781; number_of_response:1; }","duration":"219.319653ms","start":"2026-04-17T05:02:14.981981Z","end":"2026-04-17T05:02:15.201301Z","steps":["trace[2079772916] 'process raft request' (duration: 78.493596ms)","trace[2079772916] 'compare' (duration: 139.703675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:02:15.201467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.393988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-17T05:02:15.201502Z","caller":"traceutil/trace.go:171","msg":"trace[281758175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20781; }","duration":"136.49106ms","start":"2026-04-17T05:02:15.065Z","end":"2026-04-17T05:02:15.201491Z","steps":["trace[281758175] 'agreement among raft nodes before linearized reading' (duration: 136.333887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:02:15.206554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.296067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-17T05:02:15.206594Z","caller":"traceutil/trace.go:171","msg":"trace[1032215387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20782; }","duration":"141.355888ms","start":"2026-04-17T05:02:15.065229Z","end":"2026-04-17T05:02:15.206585Z","steps":["trace[1032215387] 'agreement among raft nodes before linearized reading' (duration: 141.213776ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:02:15.206578Z","caller":"traceutil/trace.go:171","msg":"trace[1826553123] transaction","detail":"{read_only:false; response_revision:20782; number_of_response:1; }","duration":"140.720024ms","start":"2026-04-17T05:02:15.065813Z","end":"2026-04-17T05:02:15.206533Z","steps":["trace[1826553123] 'process raft request' (duration: 140.537201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:07:09.31462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20718} {"level":"info","ts":"2026-04-17T05:07:09.541599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20718,"took":"221.186907ms","hash":2224535065,"current-db-size-bytes":61374464,"current-db-size":"61 MB","current-db-size-in-use-bytes":38817792,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-04-17T05:07:09.541669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2224535065,"revision":20718,"compact-revision":17550} {"level":"info","ts":"2026-04-17T05:07:16.81133Z","caller":"traceutil/trace.go:171","msg":"trace[353299190] transaction","detail":"{read_only:false; response_revision:23804; number_of_response:1; }","duration":"120.449542ms","start":"2026-04-17T05:07:16.690844Z","end":"2026-04-17T05:07:16.811293Z","steps":["trace[353299190] 'process raft request' (duration: 120.216948ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:12:09.325388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23752} {"level":"info","ts":"2026-04-17T05:12:09.467132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23752,"took":"136.818273ms","hash":2320944282,"current-db-size-bytes":62644224,"current-db-size":"63 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T05:12:09.467204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2320944282,"revision":23752,"compact-revision":20718} {"level":"info","ts":"2026-04-17T05:13:15.897202Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a88.wal"} {"level":"info","ts":"2026-04-17T05:17:09.333378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25991} {"level":"info","ts":"2026-04-17T05:17:09.446358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25991,"took":"107.080394ms","hash":674662625,"current-db-size-bytes":62644224,"current-db-size":"63 MB","current-db-size-in-use-bytes":33533952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T05:17:09.446409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":674662625,"revision":25991,"compact-revision":23752} {"level":"info","ts":"2026-04-17T05:19:12.34195Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T05:19:12.346421Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-17T05:19:12.34655Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}