{"level":"warn","ts":"2026-04-08T22:49:57.638573Z","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-08T22:49:57.638677Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-08T22:49:57.63875Z","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-08T22:49:57.638758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-04-08T22:49:57.638784Z","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-08T22:49:57.639443Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-04-08T22:49:57.639516Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-08T22:49:57.673661Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"33.91723ms"} {"level":"info","ts":"2026-04-08T22:49:57.716236Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-04-08T22:49:57.716335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:49:57.71638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-04-08T22:49:57.716394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:49:57.716407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-04-08T22:49:57.716447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-04-08T22:49:57.73999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:49:57.749918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:49:57.764449Z","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-08T22:49:57.767832Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:49:57.768309Z","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-08T22:49:57.768501Z","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-08T22:49:57.768522Z","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-08T22:49:57.76896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:49:57.775182Z","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-08T22:49:57.775571Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-04-08T22:49:57.775627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-04-08T22:49:57.775777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:49:57.775821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:49:57.777609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-04-08T22:49:57.777739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-04-08T22:49:58.216856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:49:58.217037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:49:58.217081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-04-08T22:49:58.21711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:49:58.217128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-04-08T22:49:58.217147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-04-08T22:49:58.217165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-04-08T22:49:58.219112Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:49:58.220358Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:49:58.220642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:49:58.22073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:49:58.220808Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:49:58.221022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:49:58.220935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:49:58.221218Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:49:58.221267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:49:58.224268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:49:58.23314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"warn","ts":"2026-04-08T22:50:00.930291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.526679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T22:50:00.930463Z","caller":"traceutil/trace.go:171","msg":"trace[1657844941] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"380.360949ms","start":"2026-04-08T22:50:00.550082Z","end":"2026-04-08T22:50:00.930443Z","steps":["trace[1657844941] 'read index received' (duration: 164.872225ms)","trace[1657844941] 'applied index is now lower than readState.Index' (duration: 215.487354ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:00.930534Z","caller":"traceutil/trace.go:171","msg":"trace[1619687994] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"380.820831ms","start":"2026-04-08T22:50:00.549658Z","end":"2026-04-08T22:50:00.930479Z","steps":["trace[1619687994] 'process raft request' (duration: 165.327476ms)","trace[1619687994] 'compare' (duration: 214.322435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:00.930735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:00.549643Z","time spent":"380.993992ms","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:50:00.930771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.66087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:50:00.930865Z","caller":"traceutil/trace.go:171","msg":"trace[1789235528] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:77; }","duration":"380.788289ms","start":"2026-04-08T22:50:00.550065Z","end":"2026-04-08T22:50:00.930853Z","steps":["trace[1789235528] 'agreement among raft nodes before linearized reading' (duration: 380.475758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:00.930955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:00.550058Z","time spent":"380.880854ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-04-08T22:50:00.936396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.498662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:50:00.936475Z","caller":"traceutil/trace.go:171","msg":"trace[278298566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"296.63289ms","start":"2026-04-08T22:50:00.639824Z","end":"2026-04-08T22:50:00.936457Z","steps":["trace[278298566] 'agreement among raft nodes before linearized reading' (duration: 296.433557ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:28.345091Z","caller":"traceutil/trace.go:171","msg":"trace[2096743441] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"403.317245ms","start":"2026-04-08T22:50:27.941754Z","end":"2026-04-08T22:50:28.345071Z","steps":["trace[2096743441] 'process raft request' (duration: 380.259202ms)","trace[2096743441] 'compare' (duration: 22.968537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:28.345576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:27.941736Z","time spent":"403.599936ms","remote":"127.0.0.1:38290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:50:28.357284Z","caller":"traceutil/trace.go:171","msg":"trace[1898838682] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"403.629151ms","start":"2026-04-08T22:50:27.953641Z","end":"2026-04-08T22:50:28.35727Z","steps":["trace[1898838682] 'process raft request' (duration: 403.406724ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:28.357304Z","caller":"traceutil/trace.go:171","msg":"trace[355157504] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"266.341414ms","start":"2026-04-08T22:50:28.090936Z","end":"2026-04-08T22:50:28.357277Z","steps":["trace[355157504] 'process raft request' (duration: 266.276668ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:28.357359Z","caller":"traceutil/trace.go:171","msg":"trace[674631663] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"403.052648ms","start":"2026-04-08T22:50:27.954273Z","end":"2026-04-08T22:50:28.357325Z","steps":["trace[674631663] 'process raft request' (duration: 402.852213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:28.357546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:27.954172Z","time spent":"403.243882ms","remote":"127.0.0.1:38290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:50:28.357638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:27.953627Z","time spent":"403.718308ms","remote":"127.0.0.1:38290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:50:28.35718Z","caller":"traceutil/trace.go:171","msg":"trace[1609336000] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"412.232971ms","start":"2026-04-08T22:50:27.944895Z","end":"2026-04-08T22:50:28.357128Z","steps":["trace[1609336000] 'process raft request' (duration: 411.995614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:28.358108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:27.944887Z","time spent":"413.02854ms","remote":"127.0.0.1:38490","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-08T22:50:31.792838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.470109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T22:50:31.792977Z","caller":"traceutil/trace.go:171","msg":"trace[1319809820] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:558; }","duration":"152.655672ms","start":"2026-04-08T22:50:31.6403Z","end":"2026-04-08T22:50:31.792955Z","steps":["trace[1319809820] 'range keys from in-memory index tree' (duration: 152.358671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:34.605758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.769908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:50:34.605885Z","caller":"traceutil/trace.go:171","msg":"trace[208688250] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"182.070647ms","start":"2026-04-08T22:50:34.42379Z","end":"2026-04-08T22:50:34.605861Z","steps":["trace[208688250] 'read index received' (duration: 58.998007ms)","trace[208688250] 'applied index is now lower than readState.Index' (duration: 123.07146ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:34.606021Z","caller":"traceutil/trace.go:171","msg":"trace[1796287366] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"244.2409ms","start":"2026-04-08T22:50:34.361742Z","end":"2026-04-08T22:50:34.605983Z","steps":["trace[1796287366] 'process raft request' (duration: 121.110665ms)","trace[1796287366] 'compare' (duration: 122.644829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:34.606055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.261311ms","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-08T22:50:34.606104Z","caller":"traceutil/trace.go:171","msg":"trace[1056885241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"182.350978ms","start":"2026-04-08T22:50:34.42374Z","end":"2026-04-08T22:50:34.606091Z","steps":["trace[1056885241] 'agreement among raft nodes before linearized reading' (duration: 182.191966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.086902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.19732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:50:36.087197Z","caller":"traceutil/trace.go:171","msg":"trace[136167671] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.215286619s","start":"2026-04-08T22:50:34.871862Z","end":"2026-04-08T22:50:36.087148Z","steps":["trace[136167671] 'process raft request' (duration: 608.671329ms)","trace[136167671] 'compare' (duration: 605.322674ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:36.087743Z","caller":"traceutil/trace.go:171","msg":"trace[468678359] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"487.659112ms","start":"2026-04-08T22:50:35.600049Z","end":"2026-04-08T22:50:36.087709Z","steps":["trace[468678359] 'read index received' (duration: 84.876µs)","trace[468678359] 'applied index is now lower than readState.Index' (duration: 486.918227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:36.08802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:34.871832Z","time spent":"1.215916546s","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:50:36.088279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.215093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:50:36.088456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.532387ms","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-08T22:50:36.08872Z","caller":"traceutil/trace.go:171","msg":"trace[1863090669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"467.85267ms","start":"2026-04-08T22:50:35.620845Z","end":"2026-04-08T22:50:36.088698Z","steps":["trace[1863090669] 'agreement among raft nodes before linearized reading' (duration: 467.402597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.088769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:35.620824Z","time spent":"467.934947ms","remote":"127.0.0.1:38490","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-08T22:50:36.089061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.587148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:50:36.089193Z","caller":"traceutil/trace.go:171","msg":"trace[1566016092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"157.744189ms","start":"2026-04-08T22:50:35.931415Z","end":"2026-04-08T22:50:36.089159Z","steps":["trace[1566016092] 'agreement among raft nodes before linearized reading' (duration: 156.977882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.089077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.999059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:50:36.089405Z","caller":"traceutil/trace.go:171","msg":"trace[403298903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"163.374837ms","start":"2026-04-08T22:50:35.926004Z","end":"2026-04-08T22:50:36.089378Z","steps":["trace[403298903] 'agreement among raft nodes before linearized reading' (duration: 162.46445ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:36.08836Z","caller":"traceutil/trace.go:171","msg":"trace[806992820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"488.347533ms","start":"2026-04-08T22:50:35.599995Z","end":"2026-04-08T22:50:36.088342Z","steps":["trace[806992820] 'agreement among raft nodes before linearized reading' (duration: 487.973835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.090022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:35.599973Z","time spent":"490.029219ms","remote":"127.0.0.1:38228","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-08T22:50:36.56213Z","caller":"traceutil/trace.go:171","msg":"trace[1688311407] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"467.70657ms","start":"2026-04-08T22:50:36.094396Z","end":"2026-04-08T22:50:36.562102Z","steps":["trace[1688311407] 'process raft request' (duration: 373.243732ms)","trace[1688311407] 'compare' (duration: 94.290225ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:36.56235Z","caller":"traceutil/trace.go:171","msg":"trace[1122658094] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:604; }","duration":"464.10155ms","start":"2026-04-08T22:50:36.098224Z","end":"2026-04-08T22:50:36.562326Z","steps":["trace[1122658094] 'read index received' (duration: 369.522114ms)","trace[1122658094] 'applied index is now lower than readState.Index' (duration: 94.578166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:36.562488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.254711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-08T22:50:36.56253Z","caller":"traceutil/trace.go:171","msg":"trace[2038041581] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:588; }","duration":"464.373191ms","start":"2026-04-08T22:50:36.098145Z","end":"2026-04-08T22:50:36.562518Z","steps":["trace[2038041581] 'agreement among raft nodes before linearized reading' (duration: 464.271783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.562569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:36.098132Z","time spent":"464.428275ms","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-08T22:50:36.562726Z","caller":"traceutil/trace.go:171","msg":"trace[1283250500] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"467.863581ms","start":"2026-04-08T22:50:36.094817Z","end":"2026-04-08T22:50:36.56268Z","steps":["trace[1283250500] 'process raft request' (duration: 467.250566ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:36.562798Z","caller":"traceutil/trace.go:171","msg":"trace[2140020743] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"467.504003ms","start":"2026-04-08T22:50:36.095283Z","end":"2026-04-08T22:50:36.562787Z","steps":["trace[2140020743] 'process raft request' (duration: 466.976864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:36.562876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:36.09479Z","time spent":"468.005992ms","remote":"127.0.0.1:38490","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-08T22:50:36.562905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:36.095272Z","time spent":"467.562568ms","remote":"127.0.0.1:38490","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-08T22:50:36.562951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:36.094366Z","time spent":"467.922666ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:50:52.408996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.179821ms","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-08T22:50:52.409082Z","caller":"traceutil/trace.go:171","msg":"trace[63178594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"422.325218ms","start":"2026-04-08T22:50:51.986739Z","end":"2026-04-08T22:50:52.409065Z","steps":["trace[63178594] 'range keys from in-memory index tree' (duration: 421.963883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:52.409121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:51.986718Z","time spent":"422.393637ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:36.860059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.258588ms","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-08T22:51:36.860148Z","caller":"traceutil/trace.go:171","msg":"trace[608933517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"197.369731ms","start":"2026-04-08T22:51:36.662762Z","end":"2026-04-08T22:51:36.860131Z","steps":["trace[608933517] 'range keys from in-memory index tree' (duration: 197.081973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:36.860334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.237903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-hdx6q\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-08T22:51:36.860439Z","caller":"traceutil/trace.go:171","msg":"trace[764318932] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-hdx6q; range_end:; response_count:1; response_revision:1286; }","duration":"179.385037ms","start":"2026-04-08T22:51:36.681032Z","end":"2026-04-08T22:51:36.860417Z","steps":["trace[764318932] 'range keys from in-memory index tree' (duration: 179.07052ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:42.140287Z","caller":"traceutil/trace.go:171","msg":"trace[2118185016] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"120.192527ms","start":"2026-04-08T22:51:42.020057Z","end":"2026-04-08T22:51:42.14025Z","steps":["trace[2118185016] 'process raft request' (duration: 119.94421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:42.315896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.286931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T22:51:42.316006Z","caller":"traceutil/trace.go:171","msg":"trace[287415878] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1401; }","duration":"162.488087ms","start":"2026-04-08T22:51:42.153496Z","end":"2026-04-08T22:51:42.315984Z","steps":["trace[287415878] 'count revisions from in-memory index tree' (duration: 162.181928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:42.316152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.030193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T22:51:42.31627Z","caller":"traceutil/trace.go:171","msg":"trace[678644783] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1401; }","duration":"114.230188ms","start":"2026-04-08T22:51:42.202009Z","end":"2026-04-08T22:51:42.316239Z","steps":["trace[678644783] 'range keys from in-memory index tree' (duration: 113.667313ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:45.062403Z","caller":"traceutil/trace.go:171","msg":"trace[2118226137] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"107.831615ms","start":"2026-04-08T22:51:44.95454Z","end":"2026-04-08T22:51:45.062372Z","steps":["trace[2118226137] 'process raft request' (duration: 63.042259ms)","trace[2118226137] 'compare' (duration: 44.278291ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:45.222839Z","caller":"traceutil/trace.go:171","msg":"trace[5804867] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"155.611547ms","start":"2026-04-08T22:51:45.067196Z","end":"2026-04-08T22:51:45.222807Z","steps":["trace[5804867] 'process raft request' (duration: 155.319899ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:45.223321Z","caller":"traceutil/trace.go:171","msg":"trace[434374074] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"156.026639ms","start":"2026-04-08T22:51:45.067255Z","end":"2026-04-08T22:51:45.223282Z","steps":["trace[434374074] 'process raft request' (duration: 155.478713ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:45.223511Z","caller":"traceutil/trace.go:171","msg":"trace[1361538600] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1458; }","duration":"112.570114ms","start":"2026-04-08T22:51:45.110915Z","end":"2026-04-08T22:51:45.223485Z","steps":["trace[1361538600] 'read index received' (duration: 111.610295ms)","trace[1361538600] 'applied index is now lower than readState.Index' (duration: 957.699µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:45.223609Z","caller":"traceutil/trace.go:171","msg":"trace[1378430038] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"156.268887ms","start":"2026-04-08T22:51:45.067321Z","end":"2026-04-08T22:51:45.22359Z","steps":["trace[1378430038] 'process raft request' (duration: 155.839034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:45.223658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.72376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T22:51:45.223695Z","caller":"traceutil/trace.go:171","msg":"trace[100040092] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1420; }","duration":"112.814412ms","start":"2026-04-08T22:51:45.110867Z","end":"2026-04-08T22:51:45.223682Z","steps":["trace[100040092] 'agreement among raft nodes before linearized reading' (duration: 112.699619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:47.477713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.532669ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T22:51:47.477853Z","caller":"traceutil/trace.go:171","msg":"trace[1869539363] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"288.623312ms","start":"2026-04-08T22:51:47.189204Z","end":"2026-04-08T22:51:47.477828Z","steps":["trace[1869539363] 'read index received' (duration: 35.891µs)","trace[1869539363] 'applied index is now lower than readState.Index' (duration: 288.585351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:47.478042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.794658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-rcxq8\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-04-08T22:51:47.478105Z","caller":"traceutil/trace.go:171","msg":"trace[608273767] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-rcxq8; range_end:; response_count:1; response_revision:1442; }","duration":"288.921932ms","start":"2026-04-08T22:51:47.189167Z","end":"2026-04-08T22:51:47.478089Z","steps":["trace[608273767] 'agreement among raft nodes before linearized reading' (duration: 288.727866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:47.478233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.424306ms","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-08T22:51:47.478288Z","caller":"traceutil/trace.go:171","msg":"trace[429049274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1442; }","duration":"288.604701ms","start":"2026-04-08T22:51:47.189666Z","end":"2026-04-08T22:51:47.478271Z","steps":["trace[429049274] 'agreement among raft nodes before linearized reading' (duration: 288.361174ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:49.312643Z","caller":"traceutil/trace.go:171","msg":"trace[582648768] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"317.855358ms","start":"2026-04-08T22:51:48.994769Z","end":"2026-04-08T22:51:49.312624Z","steps":["trace[582648768] 'process raft request' (duration: 317.732084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:49.31277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:48.994752Z","time spent":"317.94808ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:49.825518Z","caller":"traceutil/trace.go:171","msg":"trace[1734190280] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"267.089474ms","start":"2026-04-08T22:51:49.558405Z","end":"2026-04-08T22:51:49.825494Z","steps":["trace[1734190280] 'read index received' (duration: 266.123803ms)","trace[1734190280] 'applied index is now lower than readState.Index' (duration: 964.101µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:49.825591Z","caller":"traceutil/trace.go:171","msg":"trace[1195238317] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"267.693764ms","start":"2026-04-08T22:51:49.557867Z","end":"2026-04-08T22:51:49.825561Z","steps":["trace[1195238317] 'process raft request' (duration: 266.583999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:49.825776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.302451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:49.825823Z","caller":"traceutil/trace.go:171","msg":"trace[1249866313] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1459; }","duration":"267.430905ms","start":"2026-04-08T22:51:49.558376Z","end":"2026-04-08T22:51:49.825807Z","steps":["trace[1249866313] 'agreement among raft nodes before linearized reading' (duration: 267.253269ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:49.829489Z","caller":"traceutil/trace.go:171","msg":"trace[1231241667] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"269.912447ms","start":"2026-04-08T22:51:49.55953Z","end":"2026-04-08T22:51:49.829443Z","steps":["trace[1231241667] 'process raft request' (duration: 269.674399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:49.829556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.69727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:49.829668Z","caller":"traceutil/trace.go:171","msg":"trace[1889207376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1460; }","duration":"229.961268ms","start":"2026-04-08T22:51:49.599681Z","end":"2026-04-08T22:51:49.829643Z","steps":["trace[1889207376] 'agreement among raft nodes before linearized reading' (duration: 229.670919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:49.830079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.053223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:49.830128Z","caller":"traceutil/trace.go:171","msg":"trace[2121888411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1460; }","duration":"118.25648ms","start":"2026-04-08T22:51:49.71186Z","end":"2026-04-08T22:51:49.830116Z","steps":["trace[2121888411] 'agreement among raft nodes before linearized reading' (duration: 118.117366ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:51.68813Z","caller":"traceutil/trace.go:171","msg":"trace[316836082] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"216.213958ms","start":"2026-04-08T22:51:51.471883Z","end":"2026-04-08T22:51:51.688097Z","steps":["trace[316836082] 'process raft request' (duration: 215.828445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:51.688394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.44367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:51:51.688467Z","caller":"traceutil/trace.go:171","msg":"trace[1434841481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1514; }","duration":"171.580185ms","start":"2026-04-08T22:51:51.516868Z","end":"2026-04-08T22:51:51.688448Z","steps":["trace[1434841481] 'agreement among raft nodes before linearized reading' (duration: 171.313366ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:51.688097Z","caller":"traceutil/trace.go:171","msg":"trace[7119202] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1556; }","duration":"171.13375ms","start":"2026-04-08T22:51:51.516931Z","end":"2026-04-08T22:51:51.688064Z","steps":["trace[7119202] 'read index received' (duration: 170.694366ms)","trace[7119202] 'applied index is now lower than readState.Index' (duration: 437.844µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:51.697194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.484837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:51:51.697248Z","caller":"traceutil/trace.go:171","msg":"trace[868131002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1515; }","duration":"176.578671ms","start":"2026-04-08T22:51:51.520656Z","end":"2026-04-08T22:51:51.697235Z","steps":["trace[868131002] 'agreement among raft nodes before linearized reading' (duration: 176.383644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:51.697538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.498478ms","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-08T22:51:51.69756Z","caller":"traceutil/trace.go:171","msg":"trace[526225467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1515; }","duration":"176.532409ms","start":"2026-04-08T22:51:51.521021Z","end":"2026-04-08T22:51:51.697554Z","steps":["trace[526225467] 'agreement among raft nodes before linearized reading' (duration: 176.461787ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:51.697719Z","caller":"traceutil/trace.go:171","msg":"trace[1488500654] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"152.285814ms","start":"2026-04-08T22:51:51.54541Z","end":"2026-04-08T22:51:51.697695Z","steps":["trace[1488500654] 'process raft request' (duration: 151.510487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:51.697813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.011326ms","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-08T22:51:51.697889Z","caller":"traceutil/trace.go:171","msg":"trace[1283203138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1515; }","duration":"177.100499ms","start":"2026-04-08T22:51:51.52077Z","end":"2026-04-08T22:51:51.697871Z","steps":["trace[1283203138] 'agreement among raft nodes before linearized reading' (duration: 176.935303ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:11.858072Z","caller":"traceutil/trace.go:171","msg":"trace[848042009] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"428.782041ms","start":"2026-04-08T22:52:11.429261Z","end":"2026-04-08T22:52:11.858043Z","steps":["trace[848042009] 'process raft request' (duration: 428.610404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:11.858392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.429223Z","time spent":"429.060093ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:11.932867Z","caller":"traceutil/trace.go:171","msg":"trace[1451546764] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1898; }","duration":"333.14316ms","start":"2026-04-08T22:52:11.5997Z","end":"2026-04-08T22:52:11.932843Z","steps":["trace[1451546764] 'read index received' (duration: 258.278061ms)","trace[1451546764] 'applied index is now lower than readState.Index' (duration: 74.863819ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:11.932893Z","caller":"traceutil/trace.go:171","msg":"trace[1284833635] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"357.473572ms","start":"2026-04-08T22:52:11.575379Z","end":"2026-04-08T22:52:11.932853Z","steps":["trace[1284833635] 'process raft request' (duration: 311.402529ms)","trace[1284833635] 'compare' (duration: 45.855414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:11.933053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.334138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:52:11.933058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.575349Z","time spent":"357.626018ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:11.933093Z","caller":"traceutil/trace.go:171","msg":"trace[980463441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1833; }","duration":"333.441133ms","start":"2026-04-08T22:52:11.599642Z","end":"2026-04-08T22:52:11.933083Z","steps":["trace[980463441] 'agreement among raft nodes before linearized reading' (duration: 333.326668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:11.933129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.599628Z","time spent":"333.493075ms","remote":"127.0.0.1:38228","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-08T22:52:11.932999Z","caller":"traceutil/trace.go:171","msg":"trace[2058441952] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"257.112322ms","start":"2026-04-08T22:52:11.675853Z","end":"2026-04-08T22:52:11.932965Z","steps":["trace[2058441952] 'process raft request' (duration: 256.939325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:12.433439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978059260249563780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:52:12.934335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978059260249563780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:52:12.943684Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056921819s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:52:12.94413Z","caller":"traceutil/trace.go:171","msg":"trace[390515851] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"1.060469057s","start":"2026-04-08T22:52:11.883632Z","end":"2026-04-08T22:52:12.944101Z","steps":["trace[390515851] 'process raft request' (duration: 1.060312851s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:12.944417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.883606Z","time spent":"1.060729408s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:52:13.122911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.130891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:52:13.123504Z","caller":"traceutil/trace.go:171","msg":"trace[1996230358] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"876.287692ms","start":"2026-04-08T22:52:12.247054Z","end":"2026-04-08T22:52:13.123342Z","steps":["trace[1996230358] 'process raft request' (duration: 764.617768ms)","trace[1996230358] 'compare' (duration: 109.793124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:13.123627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.24703Z","time spent":"876.541653ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:13.123495Z","caller":"traceutil/trace.go:171","msg":"trace[1645821999] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1901; }","duration":"1.190498409s","start":"2026-04-08T22:52:11.93294Z","end":"2026-04-08T22:52:13.123438Z","steps":["trace[1645821999] 'read index received' (duration: 1.010990422s)","trace[1645821999] 'applied index is now lower than readState.Index' (duration: 179.501536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:13.123727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476881677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T22:52:13.123784Z","caller":"traceutil/trace.go:171","msg":"trace[1134915888] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:1836; }","duration":"1.477009873s","start":"2026-04-08T22:52:11.646765Z","end":"2026-04-08T22:52:13.123775Z","steps":["trace[1134915888] 'agreement among raft nodes before linearized reading' (duration: 1.476794514s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.12377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311420709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:52:13.123811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189048558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:52:13.123834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.646737Z","time spent":"1.477086516s","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"info","ts":"2026-04-08T22:52:13.123844Z","caller":"traceutil/trace.go:171","msg":"trace[478444376] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1836; }","duration":"1.311508983s","start":"2026-04-08T22:52:11.812316Z","end":"2026-04-08T22:52:13.123825Z","steps":["trace[478444376] 'agreement among raft nodes before linearized reading' (duration: 1.311383358s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:13.123856Z","caller":"traceutil/trace.go:171","msg":"trace[9780436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"1.189131842s","start":"2026-04-08T22:52:11.934712Z","end":"2026-04-08T22:52:13.123844Z","steps":["trace[9780436] 'agreement among raft nodes before linearized reading' (duration: 1.189067259s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.123887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.93469Z","time spent":"1.189191064s","remote":"127.0.0.1:38220","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-08T22:52:13.123892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.812248Z","time spent":"1.311633728s","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:52:13.123908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306806196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:52:13.12399Z","caller":"traceutil/trace.go:171","msg":"trace[1903976716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"1.306943632s","start":"2026-04-08T22:52:11.817028Z","end":"2026-04-08T22:52:13.123972Z","steps":["trace[1903976716] 'agreement among raft nodes before linearized reading' (duration: 1.306676491s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.816988Z","time spent":"1.307045287s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:52:13.124087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.866314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:52:13.12413Z","caller":"traceutil/trace.go:171","msg":"trace[1469693354] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1836; }","duration":"662.955578ms","start":"2026-04-08T22:52:12.461162Z","end":"2026-04-08T22:52:13.124118Z","steps":["trace[1469693354] 'agreement among raft nodes before linearized reading' (duration: 662.894386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.12418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.461143Z","time spent":"663.027731ms","remote":"127.0.0.1:52568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:52:13.124212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300309964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T22:52:13.124228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384274285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T22:52:13.124303Z","caller":"traceutil/trace.go:171","msg":"trace[1003365302] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be; range_end:; response_count:1; response_revision:1836; }","duration":"1.384406141s","start":"2026-04-08T22:52:11.739884Z","end":"2026-04-08T22:52:13.12429Z","steps":["trace[1003365302] 'agreement among raft nodes before linearized reading' (duration: 1.384237793s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.739847Z","time spent":"1.384511085s","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be\" "} {"level":"warn","ts":"2026-04-08T22:52:13.124396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.292727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-08T22:52:13.124243Z","caller":"traceutil/trace.go:171","msg":"trace[1544363289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1836; }","duration":"1.300358636s","start":"2026-04-08T22:52:11.823874Z","end":"2026-04-08T22:52:13.124233Z","steps":["trace[1544363289] 'agreement among raft nodes before linearized reading' (duration: 1.300274363s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144993501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-08T22:52:13.124522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301316316s","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-08T22:52:13.124534Z","caller":"traceutil/trace.go:171","msg":"trace[18705637] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"1.145068474s","start":"2026-04-08T22:52:11.979454Z","end":"2026-04-08T22:52:13.124522Z","steps":["trace[18705637] 'agreement among raft nodes before linearized reading' (duration: 1.14495949s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082173732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T22:52:13.124552Z","caller":"traceutil/trace.go:171","msg":"trace[1428049386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1836; }","duration":"1.301392779s","start":"2026-04-08T22:52:11.823148Z","end":"2026-04-08T22:52:13.124541Z","steps":["trace[1428049386] 'agreement among raft nodes before linearized reading' (duration: 1.301318316s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.979437Z","time spent":"1.145129546s","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T22:52:13.124583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.823122Z","time spent":"1.301454662s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T22:52:13.124588Z","caller":"traceutil/trace.go:171","msg":"trace[1772797911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be; range_end:; response_count:1; response_revision:1836; }","duration":"1.082484775s","start":"2026-04-08T22:52:12.042086Z","end":"2026-04-08T22:52:13.124571Z","steps":["trace[1772797911] 'agreement among raft nodes before linearized reading' (duration: 1.082139801s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.124664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.04207Z","time spent":"1.08257761s","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6daed302-4680-4664-9af2-ec129fd925be\" "} {"level":"warn","ts":"2026-04-08T22:52:13.124631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.823864Z","time spent":"1.300641858s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:52:13.124433Z","caller":"traceutil/trace.go:171","msg":"trace[161981959] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1836; }","duration":"786.362209ms","start":"2026-04-08T22:52:12.338058Z","end":"2026-04-08T22:52:13.12442Z","steps":["trace[161981959] 'agreement among raft nodes before linearized reading' (duration: 786.276265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.125061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.338037Z","time spent":"787.004736ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-08T22:52:13.126382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303404375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:52:13.1265Z","caller":"traceutil/trace.go:171","msg":"trace[1417900820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1836; }","duration":"1.303548061s","start":"2026-04-08T22:52:11.822928Z","end":"2026-04-08T22:52:13.126476Z","steps":["trace[1417900820] 'agreement among raft nodes before linearized reading' (duration: 1.300617266s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.126685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.822911Z","time spent":"1.30375964s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:52:27.708723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978059260249564036,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:52:27.829948Z","caller":"traceutil/trace.go:171","msg":"trace[138427624] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"647.802073ms","start":"2026-04-08T22:52:27.182122Z","end":"2026-04-08T22:52:27.829924Z","steps":["trace[138427624] 'process raft request' (duration: 647.603793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.830627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.182097Z","time spent":"647.923999ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:27.833994Z","caller":"traceutil/trace.go:171","msg":"trace[1384349273] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2035; }","duration":"625.46817ms","start":"2026-04-08T22:52:27.208483Z","end":"2026-04-08T22:52:27.833951Z","steps":["trace[1384349273] 'read index received' (duration: 621.291503ms)","trace[1384349273] 'applied index is now lower than readState.Index' (duration: 4.175907ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:27.834338Z","caller":"traceutil/trace.go:171","msg":"trace[1128166511] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"650.721981ms","start":"2026-04-08T22:52:27.183588Z","end":"2026-04-08T22:52:27.83431Z","steps":["trace[1128166511] 'process raft request' (duration: 650.249429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.834407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.183571Z","time spent":"650.789834ms","remote":"127.0.0.1:38490","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-08T22:52:27.834597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.11406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T22:52:27.834631Z","caller":"traceutil/trace.go:171","msg":"trace[229662821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1965; }","duration":"626.199354ms","start":"2026-04-08T22:52:27.208423Z","end":"2026-04-08T22:52:27.834622Z","steps":["trace[229662821] 'agreement among raft nodes before linearized reading' (duration: 626.115221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.834666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.20841Z","time spent":"626.237986ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:52:27.834834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.807566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T22:52:27.834863Z","caller":"traceutil/trace.go:171","msg":"trace[501601987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1965; }","duration":"625.89263ms","start":"2026-04-08T22:52:27.208955Z","end":"2026-04-08T22:52:27.834848Z","steps":["trace[501601987] 'agreement among raft nodes before linearized reading' (duration: 625.821017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.834883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.208928Z","time spent":"625.949062ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:52:27.835051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.352955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:52:27.835079Z","caller":"traceutil/trace.go:171","msg":"trace[1059977896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1965; }","duration":"237.414098ms","start":"2026-04-08T22:52:27.597652Z","end":"2026-04-08T22:52:27.835066Z","steps":["trace[1059977896] 'agreement among raft nodes before linearized reading' (duration: 237.378247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.835261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.837285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T22:52:27.835295Z","caller":"traceutil/trace.go:171","msg":"trace[1917863096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1965; }","duration":"587.880167ms","start":"2026-04-08T22:52:27.247399Z","end":"2026-04-08T22:52:27.83528Z","steps":["trace[1917863096] 'agreement among raft nodes before linearized reading' (duration: 587.796213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.835315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.24739Z","time spent":"587.91999ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T22:52:27.835622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.723966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:52:27.835674Z","caller":"traceutil/trace.go:171","msg":"trace[882823783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1965; }","duration":"606.798219ms","start":"2026-04-08T22:52:27.228862Z","end":"2026-04-08T22:52:27.835661Z","steps":["trace[882823783] 'agreement among raft nodes before linearized reading' (duration: 606.649402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.835707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.22885Z","time spent":"606.849061ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:52:27.835842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.765872ms","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-08T22:52:27.835897Z","caller":"traceutil/trace.go:171","msg":"trace[695913393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1965; }","duration":"626.826704ms","start":"2026-04-08T22:52:27.209048Z","end":"2026-04-08T22:52:27.835875Z","steps":["trace[695913393] 'agreement among raft nodes before linearized reading' (duration: 626.695707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.835927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.209043Z","time spent":"626.876767ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:52:38.159997Z","caller":"traceutil/trace.go:171","msg":"trace[499681087] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"133.170208ms","start":"2026-04-08T22:52:38.026798Z","end":"2026-04-08T22:52:38.159969Z","steps":["trace[499681087] 'process raft request' (duration: 132.989029ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:38.159853Z","caller":"traceutil/trace.go:171","msg":"trace[608874504] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"133.235151ms","start":"2026-04-08T22:52:38.026588Z","end":"2026-04-08T22:52:38.159823Z","steps":["trace[608874504] 'process raft request' (duration: 87.168304ms)","trace[608874504] 'compare' (duration: 45.880698ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:44.196359Z","caller":"traceutil/trace.go:171","msg":"trace[1905646577] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"230.590106ms","start":"2026-04-08T22:52:43.965742Z","end":"2026-04-08T22:52:44.196332Z","steps":["trace[1905646577] 'process raft request' (duration: 171.311845ms)","trace[1905646577] 'compare' (duration: 58.823777ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:44.379109Z","caller":"traceutil/trace.go:171","msg":"trace[1111957162] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"412.549127ms","start":"2026-04-08T22:52:43.966519Z","end":"2026-04-08T22:52:44.379068Z","steps":["trace[1111957162] 'process raft request' (duration: 367.870658ms)","trace[1111957162] 'compare' (duration: 44.289439ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:44.379115Z","caller":"traceutil/trace.go:171","msg":"trace[1758446922] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"411.418219ms","start":"2026-04-08T22:52:43.96767Z","end":"2026-04-08T22:52:44.379089Z","steps":["trace[1758446922] 'process raft request' (duration: 411.183217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:44.379286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:43.966509Z","time spent":"412.687935ms","remote":"127.0.0.1:38490","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-08T22:52:44.379386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:43.967663Z","time spent":"411.64392ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:44.379593Z","caller":"traceutil/trace.go:171","msg":"trace[1497597454] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"197.378866ms","start":"2026-04-08T22:52:44.182171Z","end":"2026-04-08T22:52:44.379549Z","steps":["trace[1497597454] 'process raft request' (duration: 196.87208ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:44.379191Z","caller":"traceutil/trace.go:171","msg":"trace[786534376] linearizableReadLoop","detail":"{readStateIndex:2143; appliedIndex:2140; }","duration":"370.443769ms","start":"2026-04-08T22:52:44.008718Z","end":"2026-04-08T22:52:44.379162Z","steps":["trace[786534376] 'read index received' (duration: 128.411436ms)","trace[786534376] 'applied index is now lower than readState.Index' (duration: 242.029233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:44.379722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.976997ms","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-08T22:52:44.379806Z","caller":"traceutil/trace.go:171","msg":"trace[1097983194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2068; }","duration":"371.118054ms","start":"2026-04-08T22:52:44.008668Z","end":"2026-04-08T22:52:44.379786Z","steps":["trace[1097983194] 'agreement among raft nodes before linearized reading' (duration: 370.783757ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:44.379848Z","caller":"traceutil/trace.go:171","msg":"trace[1113720584] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"195.581562ms","start":"2026-04-08T22:52:44.184252Z","end":"2026-04-08T22:52:44.379834Z","steps":["trace[1113720584] 'process raft request' (duration: 195.322939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:44.379866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:44.008642Z","time spent":"371.214479ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:52:45.453075Z","caller":"traceutil/trace.go:171","msg":"trace[1372093120] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"134.882789ms","start":"2026-04-08T22:52:45.318167Z","end":"2026-04-08T22:52:45.45305Z","steps":["trace[1372093120] 'process raft request' (duration: 134.713951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:46.439809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.423231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:52:46.440238Z","caller":"traceutil/trace.go:171","msg":"trace[1026177850] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"531.134025ms","start":"2026-04-08T22:52:45.909073Z","end":"2026-04-08T22:52:46.440207Z","steps":["trace[1026177850] 'process raft request' (duration: 76.175796ms)","trace[1026177850] 'compare' (duration: 454.273024ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:46.440268Z","caller":"traceutil/trace.go:171","msg":"trace[862906526] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"311.449469ms","start":"2026-04-08T22:52:46.128789Z","end":"2026-04-08T22:52:46.440239Z","steps":["trace[862906526] 'process raft request' (duration: 311.323992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:46.440489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.128763Z","time spent":"311.665511ms","remote":"127.0.0.1:38490","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-08T22:52:46.440648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.802752ms","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-08T22:52:46.440713Z","caller":"traceutil/trace.go:171","msg":"trace[542174561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2074; }","duration":"472.886227ms","start":"2026-04-08T22:52:45.967798Z","end":"2026-04-08T22:52:46.440684Z","steps":["trace[542174561] 'agreement among raft nodes before linearized reading' (duration: 472.718428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:46.440744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:45.967778Z","time spent":"472.959221ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:52:46.44049Z","caller":"traceutil/trace.go:171","msg":"trace[545346049] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2148; }","duration":"472.55862ms","start":"2026-04-08T22:52:45.967854Z","end":"2026-04-08T22:52:46.440412Z","steps":["trace[545346049] 'read index received' (duration: 17.311655ms)","trace[545346049] 'applied index is now lower than readState.Index' (duration: 455.244535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:46.44101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:45.909036Z","time spent":"531.265203ms","remote":"127.0.0.1:38490","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-08T22:52:47.252157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.485104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:52:47.252361Z","caller":"traceutil/trace.go:171","msg":"trace[1293723185] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"867.062676ms","start":"2026-04-08T22:52:46.385267Z","end":"2026-04-08T22:52:47.25233Z","steps":["trace[1293723185] 'process raft request' (duration: 299.298538ms)","trace[1293723185] 'compare' (duration: 565.856879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.252495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.38525Z","time spent":"867.163761ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:47.253035Z","caller":"traceutil/trace.go:171","msg":"trace[424977065] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"867.455698ms","start":"2026-04-08T22:52:46.385546Z","end":"2026-04-08T22:52:47.253002Z","steps":["trace[424977065] 'process raft request' (duration: 867.177013ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:47.253305Z","caller":"traceutil/trace.go:171","msg":"trace[2009534609] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"807.653625ms","start":"2026-04-08T22:52:46.445632Z","end":"2026-04-08T22:52:47.253286Z","steps":["trace[2009534609] 'process raft request' (duration: 807.312398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:47.253299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.385518Z","time spent":"867.657078ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:52:47.253406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.445611Z","time spent":"807.74788ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:47.253357Z","caller":"traceutil/trace.go:171","msg":"trace[2096467568] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2150; }","duration":"812.438404ms","start":"2026-04-08T22:52:46.44089Z","end":"2026-04-08T22:52:47.253328Z","steps":["trace[2096467568] 'read index received' (duration: 243.609299ms)","trace[2096467568] 'applied index is now lower than readState.Index' (duration: 568.826434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.616858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.847083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T22:52:47.617028Z","caller":"traceutil/trace.go:171","msg":"trace[438220926] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2153; }","duration":"285.684842ms","start":"2026-04-08T22:52:47.331326Z","end":"2026-04-08T22:52:47.617011Z","steps":["trace[438220926] 'read index received' (duration: 61.873µs)","trace[438220926] 'applied index is now lower than readState.Index' (duration: 285.620829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.617102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232123074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T22:52:47.617214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478198691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:52:47.617195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270410511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:52:47.617283Z","caller":"traceutil/trace.go:171","msg":"trace[415618539] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2077; }","duration":"1.478276385s","start":"2026-04-08T22:52:46.138983Z","end":"2026-04-08T22:52:47.61726Z","steps":["trace[415618539] 'agreement among raft nodes before linearized reading' (duration: 1.114851633s)","trace[415618539] 'count revisions from in-memory index tree' (duration: 363.297536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.617252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416412326s","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-08T22:52:47.617381Z","caller":"traceutil/trace.go:171","msg":"trace[1795824684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2077; }","duration":"1.416599156s","start":"2026-04-08T22:52:46.200764Z","end":"2026-04-08T22:52:47.617363Z","steps":["trace[1795824684] 'agreement among raft nodes before linearized reading' (duration: 1.052965472s)","trace[1795824684] 'range keys from in-memory index tree' (duration: 363.427802ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:47.617383Z","caller":"traceutil/trace.go:171","msg":"trace[1556738931] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2077; }","duration":"1.270668355s","start":"2026-04-08T22:52:46.346619Z","end":"2026-04-08T22:52:47.617287Z","steps":["trace[1556738931] 'agreement among raft nodes before linearized reading' (duration: 907.124216ms)","trace[1556738931] 'count revisions from in-memory index tree' (duration: 363.320297ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:47.617385Z","caller":"traceutil/trace.go:171","msg":"trace[1517622693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2077; }","duration":"1.232308834s","start":"2026-04-08T22:52:46.384911Z","end":"2026-04-08T22:52:47.61722Z","steps":["trace[1517622693] 'agreement among raft nodes before linearized reading' (duration: 868.804388ms)","trace[1517622693] 'range keys from in-memory index tree' (duration: 363.225522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.617427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.200733Z","time spent":"1.41668393s","remote":"127.0.0.1:38490","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-08T22:52:47.617511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.384899Z","time spent":"1.232539966s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:52:47.617562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.346552Z","time spent":"1.270942458s","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:52:47.61767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64039225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T22:52:47.617812Z","caller":"traceutil/trace.go:171","msg":"trace[463614353] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"1.640611032s","start":"2026-04-08T22:52:45.977162Z","end":"2026-04-08T22:52:47.617773Z","steps":["trace[463614353] 'agreement among raft nodes before linearized reading' (duration: 1.276757937s)","trace[463614353] 'range keys from in-memory index tree' (duration: 362.959858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.617379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.138893Z","time spent":"1.478467646s","remote":"127.0.0.1:38486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:52:47.617884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:45.97714Z","time spent":"1.640730838s","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T22:52:47.61758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.252092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:52:47.618038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233342587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:52:47.618072Z","caller":"traceutil/trace.go:171","msg":"trace[1603127843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2077; }","duration":"1.233372469s","start":"2026-04-08T22:52:46.384687Z","end":"2026-04-08T22:52:47.61806Z","steps":["trace[1603127843] 'agreement among raft nodes before linearized reading' (duration: 868.984926ms)","trace[1603127843] 'range keys from in-memory index tree' (duration: 364.299508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.618102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.384592Z","time spent":"1.233504166s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T22:52:47.618057Z","caller":"traceutil/trace.go:171","msg":"trace[755925936] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2077; }","duration":"286.782879ms","start":"2026-04-08T22:52:47.331257Z","end":"2026-04-08T22:52:47.61804Z","steps":["trace[755925936] 'agreement among raft nodes before linearized reading' (duration: 286.271222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:47.617875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210804739s","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-08T22:52:47.61835Z","caller":"traceutil/trace.go:171","msg":"trace[699190428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2077; }","duration":"1.211266482s","start":"2026-04-08T22:52:46.407033Z","end":"2026-04-08T22:52:47.6183Z","steps":["trace[699190428] 'agreement among raft nodes before linearized reading' (duration: 846.668399ms)","trace[699190428] 'range keys from in-memory index tree' (duration: 364.108638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:47.618503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:46.40702Z","time spent":"1.211429351s","remote":"127.0.0.1:38490","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-08T22:54:19.841667Z","caller":"traceutil/trace.go:171","msg":"trace[1721574332] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"128.041635ms","start":"2026-04-08T22:54:19.713593Z","end":"2026-04-08T22:54:19.841635Z","steps":["trace[1721574332] 'process raft request' (duration: 127.749317ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:54:19.842143Z","caller":"traceutil/trace.go:171","msg":"trace[2003872745] linearizableReadLoop","detail":"{readStateIndex:2703; appliedIndex:2703; }","duration":"109.734074ms","start":"2026-04-08T22:54:19.732369Z","end":"2026-04-08T22:54:19.842103Z","steps":["trace[2003872745] 'read index received' (duration: 109.715023ms)","trace[2003872745] 'applied index is now lower than readState.Index' (duration: 15.241µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:54:19.842439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.053684ms","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-08T22:54:19.842488Z","caller":"traceutil/trace.go:171","msg":"trace[1519002284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2605; }","duration":"110.164151ms","start":"2026-04-08T22:54:19.73231Z","end":"2026-04-08T22:54:19.842474Z","steps":["trace[1519002284] 'agreement among raft nodes before linearized reading' (duration: 109.937327ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:54:30.354787Z","caller":"traceutil/trace.go:171","msg":"trace[259425179] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"456.202365ms","start":"2026-04-08T22:54:29.898561Z","end":"2026-04-08T22:54:30.354764Z","steps":["trace[259425179] 'process raft request' (duration: 456.167333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.354958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:54:29.898527Z","time spent":"456.340944ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:54:30.355417Z","caller":"traceutil/trace.go:171","msg":"trace[209497300] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"466.857093ms","start":"2026-04-08T22:54:29.888551Z","end":"2026-04-08T22:54:30.355408Z","steps":["trace[209497300] 'process raft request' (duration: 466.01841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.355464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:54:29.888524Z","time spent":"466.916647ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:54:30.355561Z","caller":"traceutil/trace.go:171","msg":"trace[127687438] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2816; }","duration":"465.486038ms","start":"2026-04-08T22:54:29.890067Z","end":"2026-04-08T22:54:30.355553Z","steps":["trace[127687438] 'read index received' (duration: 464.426451ms)","trace[127687438] 'applied index is now lower than readState.Index' (duration: 1.058717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:54:30.355774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.706332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-08T22:54:30.355799Z","caller":"traceutil/trace.go:171","msg":"trace[1767982530] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2718; }","duration":"465.780966ms","start":"2026-04-08T22:54:29.890011Z","end":"2026-04-08T22:54:30.355792Z","steps":["trace[1767982530] 'agreement among raft nodes before linearized reading' (duration: 465.665549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.355819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:54:29.889998Z","time spent":"465.817719ms","remote":"127.0.0.1:38420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-08T22:54:30.355977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.484703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T22:54:30.355997Z","caller":"traceutil/trace.go:171","msg":"trace[631146441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2718; }","duration":"445.542716ms","start":"2026-04-08T22:54:29.910447Z","end":"2026-04-08T22:54:30.355989Z","steps":["trace[631146441] 'agreement among raft nodes before linearized reading' (duration: 445.488692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.356014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:54:29.910431Z","time spent":"445.578048ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:54:30.356281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.145039ms","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-08T22:54:30.356311Z","caller":"traceutil/trace.go:171","msg":"trace[917281812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2718; }","duration":"108.236035ms","start":"2026-04-08T22:54:30.248068Z","end":"2026-04-08T22:54:30.356304Z","steps":["trace[917281812] 'agreement among raft nodes before linearized reading' (duration: 108.170361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.356387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.198564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:54:30.356407Z","caller":"traceutil/trace.go:171","msg":"trace[1014986830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2718; }","duration":"112.243906ms","start":"2026-04-08T22:54:30.244158Z","end":"2026-04-08T22:54:30.356402Z","steps":["trace[1014986830] 'agreement among raft nodes before linearized reading' (duration: 112.192643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.35649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.435061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:54:30.356512Z","caller":"traceutil/trace.go:171","msg":"trace[1824278602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2718; }","duration":"120.477934ms","start":"2026-04-08T22:54:30.236029Z","end":"2026-04-08T22:54:30.356507Z","steps":["trace[1824278602] 'agreement among raft nodes before linearized reading' (duration: 120.42173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.356675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.402088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:54:30.356703Z","caller":"traceutil/trace.go:171","msg":"trace[399155322] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2718; }","duration":"134.43612ms","start":"2026-04-08T22:54:30.22226Z","end":"2026-04-08T22:54:30.356696Z","steps":["trace[399155322] 'agreement among raft nodes before linearized reading' (duration: 134.391237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.356981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.85318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T22:54:30.358445Z","caller":"traceutil/trace.go:171","msg":"trace[1674671656] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2718; }","duration":"206.376876ms","start":"2026-04-08T22:54:30.152057Z","end":"2026-04-08T22:54:30.358434Z","steps":["trace[1674671656] 'agreement among raft nodes before linearized reading' (duration: 204.868921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:30.356942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.084785ms","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-08T22:54:30.358892Z","caller":"traceutil/trace.go:171","msg":"trace[1507870798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2718; }","duration":"145.788083ms","start":"2026-04-08T22:54:30.212783Z","end":"2026-04-08T22:54:30.358571Z","steps":["trace[1507870798] 'agreement among raft nodes before linearized reading' (duration: 143.956707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:55:02.831386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.069512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:55:02.831714Z","caller":"traceutil/trace.go:171","msg":"trace[205252057] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"238.061056ms","start":"2026-04-08T22:55:02.593617Z","end":"2026-04-08T22:55:02.831678Z","steps":["trace[205252057] 'process raft request' (duration: 95.592838ms)","trace[205252057] 'compare' (duration: 141.895598ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:55:02.831955Z","caller":"traceutil/trace.go:171","msg":"trace[1191546366] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"238.155563ms","start":"2026-04-08T22:55:02.593783Z","end":"2026-04-08T22:55:02.831938Z","steps":["trace[1191546366] 'process raft request' (duration: 237.729948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:55:02.832325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.461742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T22:55:02.832423Z","caller":"traceutil/trace.go:171","msg":"trace[1662347705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2900; }","duration":"222.622927ms","start":"2026-04-08T22:55:02.609778Z","end":"2026-04-08T22:55:02.832401Z","steps":["trace[1662347705] 'agreement among raft nodes before linearized reading' (duration: 222.324437ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:55:02.833044Z","caller":"traceutil/trace.go:171","msg":"trace[1117286045] linearizableReadLoop","detail":"{readStateIndex:3007; appliedIndex:3005; }","duration":"221.751057ms","start":"2026-04-08T22:55:02.609832Z","end":"2026-04-08T22:55:02.831583Z","steps":["trace[1117286045] 'read index received' (duration: 79.320217ms)","trace[1117286045] 'applied index is now lower than readState.Index' (duration: 142.42946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:55:02.833279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.319603ms","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-08T22:55:02.833341Z","caller":"traceutil/trace.go:171","msg":"trace[2001849991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2900; }","duration":"223.39452ms","start":"2026-04-08T22:55:02.609933Z","end":"2026-04-08T22:55:02.833327Z","steps":["trace[2001849991] 'agreement among raft nodes before linearized reading' (duration: 223.216406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:55:03.234112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.019715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:55:03.234566Z","caller":"traceutil/trace.go:171","msg":"trace[1885479461] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"395.107843ms","start":"2026-04-08T22:55:02.83943Z","end":"2026-04-08T22:55:03.234538Z","steps":["trace[1885479461] 'process raft request' (duration: 394.842562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:55:03.234801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:55:02.839407Z","time spent":"395.290596ms","remote":"127.0.0.1:38490","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-08T22:55:03.235002Z","caller":"traceutil/trace.go:171","msg":"trace[1963721664] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"396.652289ms","start":"2026-04-08T22:55:02.837838Z","end":"2026-04-08T22:55:03.23449Z","steps":["trace[1963721664] 'process raft request' (duration: 211.150761ms)","trace[1963721664] 'compare' (duration: 183.99279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:55:03.23525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:55:02.837816Z","time spent":"397.282099ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:55:08.502776Z","caller":"traceutil/trace.go:171","msg":"trace[1946615968] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"303.074153ms","start":"2026-04-08T22:55:08.19967Z","end":"2026-04-08T22:55:08.502744Z","steps":["trace[1946615968] 'process raft request' (duration: 302.873029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:55:08.502977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:55:08.19964Z","time spent":"303.226888ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:56:09.03822Z","caller":"traceutil/trace.go:171","msg":"trace[2066561413] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"187.652028ms","start":"2026-04-08T22:56:08.85053Z","end":"2026-04-08T22:56:09.038182Z","steps":["trace[2066561413] 'process raft request' (duration: 187.345198ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:09.042102Z","caller":"traceutil/trace.go:171","msg":"trace[2121420746] transaction","detail":"{read_only:false; response_revision:3245; number_of_response:1; }","duration":"149.19589ms","start":"2026-04-08T22:56:08.892874Z","end":"2026-04-08T22:56:09.04207Z","steps":["trace[2121420746] 'process raft request' (duration: 149.10988ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:09.042109Z","caller":"traceutil/trace.go:171","msg":"trace[1535748762] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"179.45266ms","start":"2026-04-08T22:56:08.862622Z","end":"2026-04-08T22:56:09.042075Z","steps":["trace[1535748762] 'process raft request' (duration: 179.178739ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:24.080842Z","caller":"traceutil/trace.go:171","msg":"trace[634551442] transaction","detail":"{read_only:false; response_revision:3325; number_of_response:1; }","duration":"171.480032ms","start":"2026-04-08T22:56:23.909329Z","end":"2026-04-08T22:56:24.080809Z","steps":["trace[634551442] 'process raft request' (duration: 133.604617ms)","trace[634551442] 'compare' (duration: 37.539011ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:56:35.773653Z","caller":"traceutil/trace.go:171","msg":"trace[1037357072] transaction","detail":"{read_only:false; response_revision:3384; number_of_response:1; }","duration":"137.216355ms","start":"2026-04-08T22:56:35.636406Z","end":"2026-04-08T22:56:35.773622Z","steps":["trace[1037357072] 'process raft request' (duration: 71.086575ms)","trace[1037357072] 'compare' (duration: 66.011079ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:56:39.969907Z","caller":"traceutil/trace.go:171","msg":"trace[653991717] transaction","detail":"{read_only:false; response_revision:3403; number_of_response:1; }","duration":"158.392134ms","start":"2026-04-08T22:56:39.811473Z","end":"2026-04-08T22:56:39.969866Z","steps":["trace[653991717] 'process raft request' (duration: 158.115419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.397887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.210806ms","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-08T22:56:40.398102Z","caller":"traceutil/trace.go:171","msg":"trace[1406478457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3405; }","duration":"163.51781ms","start":"2026-04-08T22:56:40.234564Z","end":"2026-04-08T22:56:40.398082Z","steps":["trace[1406478457] 'range keys from in-memory index tree' (duration: 162.965993ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:36.505273Z","caller":"traceutil/trace.go:171","msg":"trace[994263553] linearizableReadLoop","detail":"{readStateIndex:3926; appliedIndex:3925; }","duration":"167.59635ms","start":"2026-04-08T22:57:36.337652Z","end":"2026-04-08T22:57:36.505249Z","steps":["trace[994263553] 'read index received' (duration: 167.329752ms)","trace[994263553] 'applied index is now lower than readState.Index' (duration: 265.528µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:57:36.50534Z","caller":"traceutil/trace.go:171","msg":"trace[2033960196] transaction","detail":"{read_only:false; response_revision:3784; number_of_response:1; }","duration":"177.905403ms","start":"2026-04-08T22:57:36.327403Z","end":"2026-04-08T22:57:36.505308Z","steps":["trace[2033960196] 'process raft request' (duration: 177.670417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:36.505645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.960339ms","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-08T22:57:36.50576Z","caller":"traceutil/trace.go:171","msg":"trace[1242660347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3784; }","duration":"168.139864ms","start":"2026-04-08T22:57:36.337598Z","end":"2026-04-08T22:57:36.505738Z","steps":["trace[1242660347] 'agreement among raft nodes before linearized reading' (duration: 167.767994ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:39.806985Z","caller":"traceutil/trace.go:171","msg":"trace[1810478938] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"173.596091ms","start":"2026-04-08T22:57:39.63336Z","end":"2026-04-08T22:57:39.806956Z","steps":["trace[1810478938] 'process raft request' (duration: 103.843919ms)","trace[1810478938] 'compare' (duration: 69.658328ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:26.645343Z","caller":"traceutil/trace.go:171","msg":"trace[390000924] transaction","detail":"{read_only:false; response_revision:4117; number_of_response:1; }","duration":"128.624027ms","start":"2026-04-08T22:58:26.516694Z","end":"2026-04-08T22:58:26.645318Z","steps":["trace[390000924] 'process raft request' (duration: 66.781118ms)","trace[390000924] 'compare' (duration: 61.725625ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:26.67581Z","caller":"traceutil/trace.go:171","msg":"trace[128914223] transaction","detail":"{read_only:false; response_revision:4118; number_of_response:1; }","duration":"131.384706ms","start":"2026-04-08T22:58:26.544389Z","end":"2026-04-08T22:58:26.675773Z","steps":["trace[128914223] 'process raft request' (duration: 131.152238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:10.80382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.263711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:59:10.804016Z","caller":"traceutil/trace.go:171","msg":"trace[1580925415] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"132.958784ms","start":"2026-04-08T22:59:10.671033Z","end":"2026-04-08T22:59:10.803992Z","steps":["trace[1580925415] 'process raft request' (duration: 12.446653ms)","trace[1580925415] 'compare' (duration: 120.093744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:17.829876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.88527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-08T22:59:17.830001Z","caller":"traceutil/trace.go:171","msg":"trace[235443364] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"231.457653ms","start":"2026-04-08T22:59:17.598526Z","end":"2026-04-08T22:59:17.829984Z","steps":["trace[235443364] 'read index received' (duration: 25.215207ms)","trace[235443364] 'applied index is now lower than readState.Index' (duration: 206.241236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:17.830095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.573948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:17.830122Z","caller":"traceutil/trace.go:171","msg":"trace[60688368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4912; }","duration":"231.6409ms","start":"2026-04-08T22:59:17.598472Z","end":"2026-04-08T22:59:17.830113Z","steps":["trace[60688368] 'agreement among raft nodes before linearized reading' (duration: 231.557827ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:17.830115Z","caller":"traceutil/trace.go:171","msg":"trace[1583879577] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4912; }","duration":"315.255815ms","start":"2026-04-08T22:59:17.514808Z","end":"2026-04-08T22:59:17.830064Z","steps":["trace[1583879577] 'process raft request' (duration: 109.01613ms)","trace[1583879577] 'compare' (duration: 205.728174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:17.83029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:17.514793Z","time spent":"315.398191ms","remote":"127.0.0.1:45146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-08T22:59:23.453902Z","caller":"traceutil/trace.go:171","msg":"trace[205241046] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"225.713096ms","start":"2026-04-08T22:59:23.228167Z","end":"2026-04-08T22:59:23.45388Z","steps":["trace[205241046] 'read index received' (duration: 225.512988ms)","trace[205241046] 'applied index is now lower than readState.Index' (duration: 199.218µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:23.454188Z","caller":"traceutil/trace.go:171","msg":"trace[704150919] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"251.428038ms","start":"2026-04-08T22:59:23.202748Z","end":"2026-04-08T22:59:23.454176Z","steps":["trace[704150919] 'process raft request' (duration: 251.0017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.454462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.27821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-08T22:59:23.454505Z","caller":"traceutil/trace.go:171","msg":"trace[519820243] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5044; }","duration":"226.360403ms","start":"2026-04-08T22:59:23.228132Z","end":"2026-04-08T22:59:23.454492Z","steps":["trace[519820243] 'agreement among raft nodes before linearized reading' (duration: 226.218457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.454716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.114856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-66c58ccd84-6lb5n.18a483e291e476bb\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-08T22:59:23.454754Z","caller":"traceutil/trace.go:171","msg":"trace[464455466] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-66c58ccd84-6lb5n.18a483e291e476bb; range_end:; response_count:1; response_revision:5044; }","duration":"223.164308ms","start":"2026-04-08T22:59:23.23158Z","end":"2026-04-08T22:59:23.454744Z","steps":["trace[464455466] 'agreement among raft nodes before linearized reading' (duration: 223.070244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.455299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.886085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T22:59:23.455341Z","caller":"traceutil/trace.go:171","msg":"trace[1234437347] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5044; }","duration":"173.950598ms","start":"2026-04-08T22:59:23.281379Z","end":"2026-04-08T22:59:23.455329Z","steps":["trace[1234437347] 'agreement among raft nodes before linearized reading' (duration: 173.858254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.455548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.838376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-08T22:59:23.455582Z","caller":"traceutil/trace.go:171","msg":"trace[1303560767] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5044; }","duration":"223.881519ms","start":"2026-04-08T22:59:23.231692Z","end":"2026-04-08T22:59:23.455573Z","steps":["trace[1303560767] 'agreement among raft nodes before linearized reading' (duration: 223.770444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.960385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.275898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:59:23.960491Z","caller":"traceutil/trace.go:171","msg":"trace[1054055185] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"500.14985ms","start":"2026-04-08T22:59:23.460325Z","end":"2026-04-08T22:59:23.960474Z","steps":["trace[1054055185] 'process raft request' (duration: 318.680254ms)","trace[1054055185] 'compare' (duration: 181.189934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:23.960553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.460305Z","time spent":"500.215403ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:23.978626Z","caller":"traceutil/trace.go:171","msg":"trace[133262071] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5236; }","duration":"516.240564ms","start":"2026-04-08T22:59:23.462368Z","end":"2026-04-08T22:59:23.978609Z","steps":["trace[133262071] 'read index received' (duration: 316.690649ms)","trace[133262071] 'applied index is now lower than readState.Index' (duration: 199.549024ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:23.978691Z","caller":"traceutil/trace.go:171","msg":"trace[70600666] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"517.347141ms","start":"2026-04-08T22:59:23.46132Z","end":"2026-04-08T22:59:23.978667Z","steps":["trace[70600666] 'process raft request' (duration: 517.143022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.978786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.461312Z","time spent":"517.427925ms","remote":"127.0.0.1:38490","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-08T22:59:23.978886Z","caller":"traceutil/trace.go:171","msg":"trace[470721452] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"509.131493ms","start":"2026-04-08T22:59:23.469745Z","end":"2026-04-08T22:59:23.978876Z","steps":["trace[470721452] 'process raft request' (duration: 508.82305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.978977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.469724Z","time spent":"509.192844ms","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:59:23.979082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.234964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T22:59:23.979108Z","caller":"traceutil/trace.go:171","msg":"trace[829886662] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5047; }","duration":"447.301616ms","start":"2026-04-08T22:59:23.5318Z","end":"2026-04-08T22:59:23.979101Z","steps":["trace[829886662] 'agreement among raft nodes before linearized reading' (duration: 447.256274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.979127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.531778Z","time spent":"447.343978ms","remote":"127.0.0.1:38582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:59:23.979153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.772466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T22:59:23.979161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.062211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77f547ef-3d60-43c7-8e0e-1cf7a34125e2\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-08T22:59:23.979175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.555356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-08T22:59:23.979217Z","caller":"traceutil/trace.go:171","msg":"trace[1161500464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5047; }","duration":"516.86966ms","start":"2026-04-08T22:59:23.462329Z","end":"2026-04-08T22:59:23.979198Z","steps":["trace[1161500464] 'agreement among raft nodes before linearized reading' (duration: 516.681372ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:23.97923Z","caller":"traceutil/trace.go:171","msg":"trace[1441972979] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5047; }","duration":"495.643529ms","start":"2026-04-08T22:59:23.48357Z","end":"2026-04-08T22:59:23.979213Z","steps":["trace[1441972979] 'agreement among raft nodes before linearized reading' (duration: 495.493813ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:23.979223Z","caller":"traceutil/trace.go:171","msg":"trace[918003248] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77f547ef-3d60-43c7-8e0e-1cf7a34125e2; range_end:; response_count:1; response_revision:5047; }","duration":"360.167306ms","start":"2026-04-08T22:59:23.619039Z","end":"2026-04-08T22:59:23.979207Z","steps":["trace[918003248] 'agreement among raft nodes before linearized reading' (duration: 360.000129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.979264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.48355Z","time spent":"495.706522ms","remote":"127.0.0.1:38340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3529,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-08T22:59:23.979268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.619019Z","time spent":"360.240968ms","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77f547ef-3d60-43c7-8e0e-1cf7a34125e2\" "} {"level":"warn","ts":"2026-04-08T22:59:23.979263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.462321Z","time spent":"516.930453ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:59:23.979295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.268185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7c8a5c65-2d49-4ce5-9dbd-e74a90e3c8be\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-08T22:59:23.97935Z","caller":"traceutil/trace.go:171","msg":"trace[310734521] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7c8a5c65-2d49-4ce5-9dbd-e74a90e3c8be; range_end:; response_count:1; response_revision:5047; }","duration":"512.334998ms","start":"2026-04-08T22:59:23.467001Z","end":"2026-04-08T22:59:23.979336Z","steps":["trace[310734521] 'agreement among raft nodes before linearized reading' (duration: 511.884199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.979386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.466995Z","time spent":"512.38373ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-7c8a5c65-2d49-4ce5-9dbd-e74a90e3c8be\" "} {"level":"warn","ts":"2026-04-08T22:59:23.97945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.213757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:23.980561Z","caller":"traceutil/trace.go:171","msg":"trace[590183514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5047; }","duration":"380.571952ms","start":"2026-04-08T22:59:23.59903Z","end":"2026-04-08T22:59:23.979602Z","steps":["trace[590183514] 'agreement among raft nodes before linearized reading' (duration: 379.944816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:23.980621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:23.59901Z","time spent":"381.597547ms","remote":"127.0.0.1:38228","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-08T22:59:24.108509Z","caller":"traceutil/trace.go:171","msg":"trace[1731250502] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5239; }","duration":"114.861777ms","start":"2026-04-08T22:59:23.993623Z","end":"2026-04-08T22:59:24.108485Z","steps":["trace[1731250502] 'read index received' (duration: 57.406282ms)","trace[1731250502] 'applied index is now lower than readState.Index' (duration: 57.454605ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:24.108622Z","caller":"traceutil/trace.go:171","msg":"trace[1147506878] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"114.961832ms","start":"2026-04-08T22:59:23.99365Z","end":"2026-04-08T22:59:24.108611Z","steps":["trace[1147506878] 'process raft request' (duration: 114.643168ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:24.108606Z","caller":"traceutil/trace.go:171","msg":"trace[1127796377] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"114.92672ms","start":"2026-04-08T22:59:23.993658Z","end":"2026-04-08T22:59:24.108584Z","steps":["trace[1127796377] 'process raft request' (duration: 114.731432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:24.10883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.184411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13296"} {"level":"info","ts":"2026-04-08T22:59:24.10887Z","caller":"traceutil/trace.go:171","msg":"trace[1055445217] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5053; }","duration":"115.246324ms","start":"2026-04-08T22:59:23.99361Z","end":"2026-04-08T22:59:24.108856Z","steps":["trace[1055445217] 'agreement among raft nodes before linearized reading' (duration: 115.096868ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:24.10854Z","caller":"traceutil/trace.go:171","msg":"trace[932194642] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"114.947881ms","start":"2026-04-08T22:59:23.99357Z","end":"2026-04-08T22:59:24.108518Z","steps":["trace[932194642] 'process raft request' (duration: 103.575167ms)","trace[932194642] 'compare' (duration: 10.855992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:24.124102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.314228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-08T22:59:24.124176Z","caller":"traceutil/trace.go:171","msg":"trace[1035064917] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5054; }","duration":"127.412933ms","start":"2026-04-08T22:59:23.996744Z","end":"2026-04-08T22:59:24.124157Z","steps":["trace[1035064917] 'agreement among raft nodes before linearized reading' (duration: 126.860449ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:24.290753Z","caller":"traceutil/trace.go:171","msg":"trace[529737259] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"120.948037ms","start":"2026-04-08T22:59:24.169778Z","end":"2026-04-08T22:59:24.290726Z","steps":["trace[529737259] 'process raft request' (duration: 47.864297ms)","trace[529737259] 'compare' (duration: 72.909152ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:24.290824Z","caller":"traceutil/trace.go:171","msg":"trace[1924363378] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"100.202413ms","start":"2026-04-08T22:59:24.19056Z","end":"2026-04-08T22:59:24.290762Z","steps":["trace[1924363378] 'process raft request' (duration: 100.109129ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:24.450659Z","caller":"traceutil/trace.go:171","msg":"trace[2083338809] linearizableReadLoop","detail":"{readStateIndex:5251; appliedIndex:5250; }","duration":"151.258648ms","start":"2026-04-08T22:59:24.299373Z","end":"2026-04-08T22:59:24.450632Z","steps":["trace[2083338809] 'read index received' (duration: 142.410791ms)","trace[2083338809] 'applied index is now lower than readState.Index' (duration: 8.846137ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:24.45069Z","caller":"traceutil/trace.go:171","msg":"trace[1917333186] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"152.338933ms","start":"2026-04-08T22:59:24.298313Z","end":"2026-04-08T22:59:24.450652Z","steps":["trace[1917333186] 'process raft request' (duration: 143.459885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:24.450883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.492298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7c8a5c65-2d49-4ce5-9dbd-e74a90e3c8be\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-08T22:59:24.450917Z","caller":"traceutil/trace.go:171","msg":"trace[1821759004] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7c8a5c65-2d49-4ce5-9dbd-e74a90e3c8be; range_end:; response_count:1; response_revision:5059; }","duration":"151.55481ms","start":"2026-04-08T22:59:24.299352Z","end":"2026-04-08T22:59:24.450907Z","steps":["trace[1821759004] 'agreement among raft nodes before linearized reading' (duration: 151.374492ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:24.538133Z","caller":"traceutil/trace.go:171","msg":"trace[1389124855] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"229.190924ms","start":"2026-04-08T22:59:24.308921Z","end":"2026-04-08T22:59:24.538112Z","steps":["trace[1389124855] 'process raft request' (duration: 229.058578ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:27.685644Z","caller":"traceutil/trace.go:171","msg":"trace[1531713993] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"211.421483ms","start":"2026-04-08T22:59:27.474166Z","end":"2026-04-08T22:59:27.685588Z","steps":["trace[1531713993] 'process raft request' (duration: 211.211184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:32.45438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.771283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-04-08T22:59:32.454564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.326788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T22:59:32.454601Z","caller":"traceutil/trace.go:171","msg":"trace[2077221680] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5179; }","duration":"106.3921ms","start":"2026-04-08T22:59:32.348198Z","end":"2026-04-08T22:59:32.45459Z","steps":["trace[2077221680] 'range keys from in-memory index tree' (duration: 106.240274ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:32.454563Z","caller":"traceutil/trace.go:171","msg":"trace[1539203402] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5179; }","duration":"139.960102ms","start":"2026-04-08T22:59:32.314583Z","end":"2026-04-08T22:59:32.454543Z","steps":["trace[1539203402] 'range keys from in-memory index tree' (duration: 139.597146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:32.454787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.469222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-04-08T22:59:32.454839Z","caller":"traceutil/trace.go:171","msg":"trace[519540357] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5179; }","duration":"133.556636ms","start":"2026-04-08T22:59:32.321269Z","end":"2026-04-08T22:59:32.454826Z","steps":["trace[519540357] 'range keys from in-memory index tree' (duration: 133.330216ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:37.420447Z","caller":"traceutil/trace.go:171","msg":"trace[481942227] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"338.11583ms","start":"2026-04-08T22:59:37.08231Z","end":"2026-04-08T22:59:37.420425Z","steps":["trace[481942227] 'process raft request' (duration: 337.972393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.4206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:37.082281Z","time spent":"338.240726ms","remote":"127.0.0.1:38490","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-08T22:59:37.869846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.095454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T22:59:37.869971Z","caller":"traceutil/trace.go:171","msg":"trace[857097249] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5429; }","duration":"496.189031ms","start":"2026-04-08T22:59:37.373752Z","end":"2026-04-08T22:59:37.869941Z","steps":["trace[857097249] 'read index received' (duration: 46.522341ms)","trace[857097249] 'applied index is now lower than readState.Index' (duration: 449.66431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:37.870431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.232336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-08T22:59:37.870626Z","caller":"traceutil/trace.go:171","msg":"trace[1255991595] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5232; }","duration":"429.471667ms","start":"2026-04-08T22:59:37.441129Z","end":"2026-04-08T22:59:37.870601Z","steps":["trace[1255991595] 'agreement among raft nodes before linearized reading' (duration: 429.124622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.870666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.790531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:59:37.870789Z","caller":"traceutil/trace.go:171","msg":"trace[1322763389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5232; }","duration":"270.951028ms","start":"2026-04-08T22:59:37.599819Z","end":"2026-04-08T22:59:37.87077Z","steps":["trace[1322763389] 'agreement among raft nodes before linearized reading' (duration: 270.787461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.870887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.878411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T22:59:37.870979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:37.441111Z","time spent":"429.808512ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-08T22:59:37.871083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.025701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-08T22:59:37.871167Z","caller":"traceutil/trace.go:171","msg":"trace[826218381] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5232; }","duration":"405.136336ms","start":"2026-04-08T22:59:37.46601Z","end":"2026-04-08T22:59:37.871147Z","steps":["trace[826218381] 'agreement among raft nodes before linearized reading' (duration: 404.906986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.871217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:37.466Z","time spent":"405.205408ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-08T22:59:37.871152Z","caller":"traceutil/trace.go:171","msg":"trace[1647319499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5232; }","duration":"144.178475ms","start":"2026-04-08T22:59:37.726753Z","end":"2026-04-08T22:59:37.870932Z","steps":["trace[1647319499] 'agreement among raft nodes before linearized reading' (duration: 143.754906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.871538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.78472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-04-08T22:59:37.871586Z","caller":"traceutil/trace.go:171","msg":"trace[833332435] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5232; }","duration":"497.861063ms","start":"2026-04-08T22:59:37.373713Z","end":"2026-04-08T22:59:37.871574Z","steps":["trace[833332435] 'agreement among raft nodes before linearized reading' (duration: 497.735238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:37.871702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:37.373699Z","time spent":"497.988919ms","remote":"127.0.0.1:38406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16575,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-08T22:59:38.590296Z","caller":"traceutil/trace.go:171","msg":"trace[1506702757] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"118.159497ms","start":"2026-04-08T22:59:38.472097Z","end":"2026-04-08T22:59:38.590257Z","steps":["trace[1506702757] 'process raft request' (duration: 105.379848ms)","trace[1506702757] 'compare' (duration: 12.673815ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:58.257319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-04-08T22:59:58.326943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"68.36233ms","hash":166070215,"current-db-size-bytes":51363840,"current-db-size":"51 MB","current-db-size-in-use-bytes":27381760,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-08T22:59:58.326994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":166070215,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-04-08T23:00:25.906971Z","caller":"traceutil/trace.go:171","msg":"trace[1683189762] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"231.293597ms","start":"2026-04-08T23:00:25.67566Z","end":"2026-04-08T23:00:25.906954Z","steps":["trace[1683189762] 'process raft request' (duration: 231.109379ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:25.906946Z","caller":"traceutil/trace.go:171","msg":"trace[848653268] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5827; }","duration":"199.583731ms","start":"2026-04-08T23:00:25.707337Z","end":"2026-04-08T23:00:25.906921Z","steps":["trace[848653268] 'read index received' (duration: 199.320678ms)","trace[848653268] 'applied index is now lower than readState.Index' (duration: 262.143µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:00:25.907151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.78648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-04-08T23:00:25.907196Z","caller":"traceutil/trace.go:171","msg":"trace[774014991] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5617; }","duration":"199.871974ms","start":"2026-04-08T23:00:25.707314Z","end":"2026-04-08T23:00:25.907186Z","steps":["trace[774014991] 'agreement among raft nodes before linearized reading' (duration: 199.689386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:25.907204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.701357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:00:25.90728Z","caller":"traceutil/trace.go:171","msg":"trace[1069833052] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5617; }","duration":"199.798621ms","start":"2026-04-08T23:00:25.707463Z","end":"2026-04-08T23:00:25.907262Z","steps":["trace[1069833052] 'agreement among raft nodes before linearized reading' (duration: 199.670975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:25.907402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.466055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:00:25.907484Z","caller":"traceutil/trace.go:171","msg":"trace[285219520] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5617; }","duration":"199.552929ms","start":"2026-04-08T23:00:25.707914Z","end":"2026-04-08T23:00:25.907466Z","steps":["trace[285219520] 'agreement among raft nodes before linearized reading' (duration: 199.433484ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:43.898475Z","caller":"traceutil/trace.go:171","msg":"trace[54806968] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"103.447942ms","start":"2026-04-08T23:00:43.794991Z","end":"2026-04-08T23:00:43.898439Z","steps":["trace[54806968] 'process raft request' (duration: 45.338276ms)","trace[54806968] 'compare' (duration: 57.991091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:01:29.213648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.247193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:01:29.214514Z","caller":"traceutil/trace.go:171","msg":"trace[1084081918] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"412.198856ms","start":"2026-04-08T23:01:28.802272Z","end":"2026-04-08T23:01:29.214471Z","steps":["trace[1084081918] 'process raft request' (duration: 179.022337ms)","trace[1084081918] 'compare' (duration: 232.001861ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:29.214577Z","caller":"traceutil/trace.go:171","msg":"trace[1940671393] transaction","detail":"{read_only:false; response_revision:6080; number_of_response:1; }","duration":"236.768526ms","start":"2026-04-08T23:01:28.977793Z","end":"2026-04-08T23:01:29.214561Z","steps":["trace[1940671393] 'process raft request' (duration: 236.594628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:29.214718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:28.802242Z","time spent":"412.367473ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:05.771229Z","caller":"traceutil/trace.go:171","msg":"trace[69147698] transaction","detail":"{read_only:false; response_revision:6261; number_of_response:1; }","duration":"382.507417ms","start":"2026-04-08T23:02:05.388701Z","end":"2026-04-08T23:02:05.771209Z","steps":["trace[69147698] 'process raft request' (duration: 382.190841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:05.771385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:05.388679Z","time spent":"382.616382ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:02:05.771645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.18846ms","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-08T23:02:05.771775Z","caller":"traceutil/trace.go:171","msg":"trace[121692090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6261; }","duration":"321.362459ms","start":"2026-04-08T23:02:05.450388Z","end":"2026-04-08T23:02:05.771751Z","steps":["trace[121692090] 'agreement among raft nodes before linearized reading' (duration: 321.015252ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:05.771144Z","caller":"traceutil/trace.go:171","msg":"trace[1385836021] linearizableReadLoop","detail":"{readStateIndex:6493; appliedIndex:6492; }","duration":"320.689765ms","start":"2026-04-08T23:02:05.450428Z","end":"2026-04-08T23:02:05.771118Z","steps":["trace[1385836021] 'read index received' (duration: 320.507076ms)","trace[1385836021] 'applied index is now lower than readState.Index' (duration: 181.299µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:05.771887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:05.450375Z","time spent":"321.498515ms","remote":"127.0.0.1:38490","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-08T23:02:05.785775Z","caller":"traceutil/trace.go:171","msg":"trace[975489720] transaction","detail":"{read_only:false; response_revision:6262; number_of_response:1; }","duration":"329.973525ms","start":"2026-04-08T23:02:05.455772Z","end":"2026-04-08T23:02:05.785745Z","steps":["trace[975489720] 'process raft request' (duration: 329.783966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:05.78578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.57527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:02:05.785842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.584834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T23:02:05.785874Z","caller":"traceutil/trace.go:171","msg":"trace[380554014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6262; }","duration":"160.663968ms","start":"2026-04-08T23:02:05.625202Z","end":"2026-04-08T23:02:05.785866Z","steps":["trace[380554014] 'agreement among raft nodes before linearized reading' (duration: 160.552203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:05.785909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:05.455757Z","time spent":"330.085491ms","remote":"127.0.0.1:38490","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-08T23:02:05.785912Z","caller":"traceutil/trace.go:171","msg":"trace[1515143488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6262; }","duration":"185.735018ms","start":"2026-04-08T23:02:05.600117Z","end":"2026-04-08T23:02:05.785852Z","steps":["trace[1515143488] 'agreement among raft nodes before linearized reading' (duration: 185.576961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:06.385752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.935448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:02:06.385871Z","caller":"traceutil/trace.go:171","msg":"trace[173700178] linearizableReadLoop","detail":"{readStateIndex:6497; appliedIndex:6496; }","duration":"286.780236ms","start":"2026-04-08T23:02:06.099071Z","end":"2026-04-08T23:02:06.385852Z","steps":["trace[173700178] 'read index received' (duration: 152.543053ms)","trace[173700178] 'applied index is now lower than readState.Index' (duration: 134.235603ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:06.385998Z","caller":"traceutil/trace.go:171","msg":"trace[672503874] transaction","detail":"{read_only:false; response_revision:6265; number_of_response:1; }","duration":"330.789646ms","start":"2026-04-08T23:02:06.055192Z","end":"2026-04-08T23:02:06.385982Z","steps":["trace[672503874] 'process raft request' (duration: 196.5095ms)","trace[672503874] 'compare' (duration: 133.806892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:06.386087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:06.055174Z","time spent":"330.86477ms","remote":"127.0.0.1:38490","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-08T23:02:06.386383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.083829ms","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":"warn","ts":"2026-04-08T23:02:06.386574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.481881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:02:06.386641Z","caller":"traceutil/trace.go:171","msg":"trace[527398571] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:6265; }","duration":"287.608137ms","start":"2026-04-08T23:02:06.099013Z","end":"2026-04-08T23:02:06.386621Z","steps":["trace[527398571] 'agreement among raft nodes before linearized reading' (duration: 287.489581ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:06.386484Z","caller":"traceutil/trace.go:171","msg":"trace[796650002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6265; }","duration":"285.224076ms","start":"2026-04-08T23:02:06.101225Z","end":"2026-04-08T23:02:06.386449Z","steps":["trace[796650002] 'agreement among raft nodes before linearized reading' (duration: 285.063648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:06.387095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.538938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-08T23:02:06.387132Z","caller":"traceutil/trace.go:171","msg":"trace[527349254] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6264; }","duration":"338.614642ms","start":"2026-04-08T23:02:06.048509Z","end":"2026-04-08T23:02:06.387123Z","steps":["trace[527349254] 'range keys from in-memory index tree' (duration: 336.501685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:06.387156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:06.048489Z","time spent":"338.659804ms","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-04-08T23:02:06.8255Z","caller":"traceutil/trace.go:171","msg":"trace[2042767204] transaction","detail":"{read_only:false; response_revision:6266; number_of_response:1; }","duration":"102.090085ms","start":"2026-04-08T23:02:06.723389Z","end":"2026-04-08T23:02:06.825479Z","steps":["trace[2042767204] 'process raft request' (duration: 101.938878ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:07.061743Z","caller":"traceutil/trace.go:171","msg":"trace[1620179404] transaction","detail":"{read_only:false; response_revision:6267; number_of_response:1; }","duration":"181.407537ms","start":"2026-04-08T23:02:06.880307Z","end":"2026-04-08T23:02:07.061715Z","steps":["trace[1620179404] 'process raft request' (duration: 176.984522ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:07.110174Z","caller":"traceutil/trace.go:171","msg":"trace[99359170] transaction","detail":"{read_only:false; response_revision:6268; number_of_response:1; }","duration":"229.826391ms","start":"2026-04-08T23:02:06.880322Z","end":"2026-04-08T23:02:07.110148Z","steps":["trace[99359170] 'process raft request' (duration: 229.626461ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:07.120009Z","caller":"traceutil/trace.go:171","msg":"trace[1637620072] linearizableReadLoop","detail":"{readStateIndex:6500; appliedIndex:6498; }","duration":"231.37009ms","start":"2026-04-08T23:02:06.888623Z","end":"2026-04-08T23:02:07.119993Z","steps":["trace[1637620072] 'read index received' (duration: 168.688391ms)","trace[1637620072] 'applied index is now lower than readState.Index' (duration: 62.680769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:07.120238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.601831ms","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-08T23:02:07.120273Z","caller":"traceutil/trace.go:171","msg":"trace[1342284074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6268; }","duration":"231.713167ms","start":"2026-04-08T23:02:06.888551Z","end":"2026-04-08T23:02:07.120264Z","steps":["trace[1342284074] 'agreement among raft nodes before linearized reading' (duration: 231.501586ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:11.840928Z","caller":"traceutil/trace.go:171","msg":"trace[2023878166] linearizableReadLoop","detail":"{readStateIndex:6524; appliedIndex:6523; }","duration":"157.305432ms","start":"2026-04-08T23:02:11.683602Z","end":"2026-04-08T23:02:11.840907Z","steps":["trace[2023878166] 'read index received' (duration: 157.090721ms)","trace[2023878166] 'applied index is now lower than readState.Index' (duration: 213.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:11.841189Z","caller":"traceutil/trace.go:171","msg":"trace[1541759633] transaction","detail":"{read_only:false; response_revision:6291; number_of_response:1; }","duration":"164.571161ms","start":"2026-04-08T23:02:11.676604Z","end":"2026-04-08T23:02:11.841175Z","steps":["trace[1541759633] 'process raft request' (duration: 164.15091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:11.841485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.828839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T23:02:11.841546Z","caller":"traceutil/trace.go:171","msg":"trace[940489199] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:6291; }","duration":"157.939584ms","start":"2026-04-08T23:02:11.683593Z","end":"2026-04-08T23:02:11.841532Z","steps":["trace[940489199] 'agreement among raft nodes before linearized reading' (duration: 157.745215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:12.251805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.961765ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-08T23:02:12.252145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:11.842889Z","time spent":"409.235767ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T23:02:12.252227Z","caller":"traceutil/trace.go:171","msg":"trace[110350384] linearizableReadLoop","detail":"{readStateIndex:6526; appliedIndex:6524; }","duration":"404.75665ms","start":"2026-04-08T23:02:11.84745Z","end":"2026-04-08T23:02:12.252206Z","steps":["trace[110350384] 'read index received' (duration: 227.374603ms)","trace[110350384] 'applied index is now lower than readState.Index' (duration: 177.380827ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:12.252454Z","caller":"traceutil/trace.go:171","msg":"trace[1242955869] transaction","detail":"{read_only:false; response_revision:6292; number_of_response:1; }","duration":"405.770801ms","start":"2026-04-08T23:02:11.846652Z","end":"2026-04-08T23:02:12.252423Z","steps":["trace[1242955869] 'process raft request' (duration: 405.384441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:12.25256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.092627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T23:02:12.252621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:11.84664Z","time spent":"405.891957ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:12.252638Z","caller":"traceutil/trace.go:171","msg":"trace[946768392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6292; }","duration":"405.189702ms","start":"2026-04-08T23:02:11.847425Z","end":"2026-04-08T23:02:12.252615Z","steps":["trace[946768392] 'agreement among raft nodes before linearized reading' (duration: 404.973531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:12.252689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.073025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T23:02:12.252712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:11.847419Z","time spent":"405.283707ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T23:02:12.252768Z","caller":"traceutil/trace.go:171","msg":"trace[244327767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6292; }","duration":"404.165649ms","start":"2026-04-08T23:02:11.848562Z","end":"2026-04-08T23:02:12.252728Z","steps":["trace[244327767] 'agreement among raft nodes before linearized reading' (duration: 403.905256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:12.252845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:11.848558Z","time spent":"404.256934ms","remote":"127.0.0.1:38490","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":"warn","ts":"2026-04-08T23:02:12.581595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.590976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:02:12.581879Z","caller":"traceutil/trace.go:171","msg":"trace[1736213] transaction","detail":"{read_only:false; response_revision:6293; number_of_response:1; }","duration":"326.60296ms","start":"2026-04-08T23:02:12.255235Z","end":"2026-04-08T23:02:12.581838Z","steps":["trace[1736213] 'process raft request' (duration: 161.621444ms)","trace[1736213] 'compare' (duration: 164.178196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:12.582034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:12.255196Z","time spent":"326.757978ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:12.582134Z","caller":"traceutil/trace.go:171","msg":"trace[348220001] transaction","detail":"{read_only:false; response_revision:6294; number_of_response:1; }","duration":"324.896045ms","start":"2026-04-08T23:02:12.25721Z","end":"2026-04-08T23:02:12.582106Z","steps":["trace[348220001] 'process raft request' (duration: 324.516416ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:12.582159Z","caller":"traceutil/trace.go:171","msg":"trace[490063392] transaction","detail":"{read_only:false; response_revision:6295; number_of_response:1; }","duration":"324.548015ms","start":"2026-04-08T23:02:12.25759Z","end":"2026-04-08T23:02:12.582138Z","steps":["trace[490063392] 'process raft request' (duration: 324.422599ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:12.582203Z","caller":"traceutil/trace.go:171","msg":"trace[1299520836] linearizableReadLoop","detail":"{readStateIndex:6529; appliedIndex:6526; }","duration":"182.748858ms","start":"2026-04-08T23:02:12.399429Z","end":"2026-04-08T23:02:12.582178Z","steps":["trace[1299520836] 'read index received' (duration: 17.450286ms)","trace[1299520836] 'applied index is now lower than readState.Index' (duration: 165.297532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:12.582289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:12.257193Z","time spent":"325.012441ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:12.582358Z","caller":"traceutil/trace.go:171","msg":"trace[657752413] transaction","detail":"{read_only:false; response_revision:6296; number_of_response:1; }","duration":"176.700513ms","start":"2026-04-08T23:02:12.405644Z","end":"2026-04-08T23:02:12.582344Z","steps":["trace[657752413] 'process raft request' (duration: 176.434318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:12.582281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:12.257553Z","time spent":"324.668313ms","remote":"127.0.0.1:38490","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-08T23:02:12.582421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.984182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-08T23:02:12.582467Z","caller":"traceutil/trace.go:171","msg":"trace[1876962299] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6296; }","duration":"183.078026ms","start":"2026-04-08T23:02:12.399376Z","end":"2026-04-08T23:02:12.582454Z","steps":["trace[1876962299] 'agreement among raft nodes before linearized reading' (duration: 182.897907ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:03:47.589633Z","caller":"traceutil/trace.go:171","msg":"trace[504340035] transaction","detail":"{read_only:false; response_revision:7033; number_of_response:1; }","duration":"137.601522ms","start":"2026-04-08T23:03:47.451998Z","end":"2026-04-08T23:03:47.5896Z","steps":["trace[504340035] 'process raft request' (duration: 56.596459ms)","trace[504340035] 'compare' (duration: 80.768473ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:35.687098Z","caller":"traceutil/trace.go:171","msg":"trace[1212176290] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"112.579179ms","start":"2026-04-08T23:04:35.574491Z","end":"2026-04-08T23:04:35.68707Z","steps":["trace[1212176290] 'process raft request' (duration: 107.973009ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:58.310908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5370} {"level":"info","ts":"2026-04-08T23:04:58.517201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5370,"took":"204.54846ms","hash":147665644,"current-db-size-bytes":51363840,"current-db-size":"51 MB","current-db-size-in-use-bytes":19025920,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-08T23:04:58.51728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":147665644,"revision":5370,"compact-revision":2873}