{"level":"warn","ts":"2026-05-04T18:11:13.164856Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:11:13.16496Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-04T18:11:13.165036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:11:13.165047Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-04T18:11:13.165086Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T18:11:13.166079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-05-04T18:11:13.166189Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-04T18:11:13.169599Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.20481ms"} {"level":"info","ts":"2026-05-04T18:11:13.177017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-05-04T18:11:13.177078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T18:11:13.177094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-05-04T18:11:13.177102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T18:11:13.177111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-05-04T18:11:13.177161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-05-04T18:11:13.182099Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T18:11:13.183599Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T18:11:13.184914Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-04T18:11:13.187128Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T18:11:13.188624Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T18:11:13.188755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T18:11:13.188772Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T18:11:13.188717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T18:11:13.190371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-05-04T18:11:13.191708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-04T18:11:13.192637Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T18:11:13.192778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-04T18:11:13.1929Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-04T18:11:13.192939Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T18:11:13.192984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T18:11:13.877977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T18:11:13.878034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T18:11:13.878057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-05-04T18:11:13.878076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-05-04T18:11:13.878088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-04T18:11:13.878132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-05-04T18:11:13.878146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-04T18:11:13.87937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T18:11:13.879371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:11:13.879425Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:11:13.879681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:11:13.880156Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T18:11:13.880193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T18:11:13.88054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:11:13.880665Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:11:13.880695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:11:13.881352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-05-04T18:11:13.88312Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T18:11:51.839172Z","caller":"traceutil/trace.go:171","msg":"trace[388997647] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"156.575155ms","start":"2026-05-04T18:11:51.682568Z","end":"2026-05-04T18:11:51.839143Z","steps":["trace[388997647] 'process raft request' (duration: 151.66039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.256513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.583809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:11:52.257072Z","caller":"traceutil/trace.go:171","msg":"trace[825648580] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"359.035869ms","start":"2026-05-04T18:11:51.898001Z","end":"2026-05-04T18:11:52.257037Z","steps":["trace[825648580] 'compare' (duration: 349.1948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.25724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:11:51.897962Z","time spent":"359.196103ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T18:11:52.257318Z","caller":"traceutil/trace.go:171","msg":"trace[1829261422] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"243.309099ms","start":"2026-05-04T18:11:52.01394Z","end":"2026-05-04T18:11:52.257249Z","steps":["trace[1829261422] 'read index received' (duration: 176.053µs)","trace[1829261422] 'applied index is now lower than readState.Index' (duration: 243.127046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:11:52.276792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.835954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.276883Z","caller":"traceutil/trace.go:171","msg":"trace[437343439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"262.989817ms","start":"2026-05-04T18:11:52.013862Z","end":"2026-05-04T18:11:52.276852Z","steps":["trace[437343439] 'agreement among raft nodes before linearized reading' (duration: 243.469452ms)","trace[437343439] 'range keys from in-memory index tree' (duration: 19.370652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:11:52.27722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.11685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-04T18:11:52.27731Z","caller":"traceutil/trace.go:171","msg":"trace[660613120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"201.291973ms","start":"2026-05-04T18:11:52.075997Z","end":"2026-05-04T18:11:52.277289Z","steps":["trace[660613120] 'agreement among raft nodes before linearized reading' (duration: 200.814914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.277334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.14146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-04T18:11:52.277434Z","caller":"traceutil/trace.go:171","msg":"trace[1790872473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"201.299523ms","start":"2026-05-04T18:11:52.07611Z","end":"2026-05-04T18:11:52.277409Z","steps":["trace[1790872473] 'agreement among raft nodes before linearized reading' (duration: 200.664011ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.388732Z","caller":"traceutil/trace.go:171","msg":"trace[258099392] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"107.139285ms","start":"2026-05-04T18:11:52.281548Z","end":"2026-05-04T18:11:52.388687Z","steps":["trace[258099392] 'process raft request' (duration: 106.897941ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.73059Z","caller":"traceutil/trace.go:171","msg":"trace[1975721195] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"293.020223ms","start":"2026-05-04T18:11:52.437552Z","end":"2026-05-04T18:11:52.730572Z","steps":["trace[1975721195] 'read index received' (duration: 292.85036ms)","trace[1975721195] 'applied index is now lower than readState.Index' (duration: 168.733µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:11:52.73063Z","caller":"traceutil/trace.go:171","msg":"trace[2058527840] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"448.874493ms","start":"2026-05-04T18:11:52.28172Z","end":"2026-05-04T18:11:52.730595Z","steps":["trace[2058527840] 'process raft request' (duration: 448.610468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.730749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.162276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.73082Z","caller":"traceutil/trace.go:171","msg":"trace[788544917] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:583; }","duration":"293.304588ms","start":"2026-05-04T18:11:52.437485Z","end":"2026-05-04T18:11:52.730789Z","steps":["trace[788544917] 'agreement among raft nodes before linearized reading' (duration: 293.203677ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.730732Z","caller":"traceutil/trace.go:171","msg":"trace[1042345142] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"440.050744ms","start":"2026-05-04T18:11:52.290649Z","end":"2026-05-04T18:11:52.7307Z","steps":["trace[1042345142] 'process raft request' (duration: 439.85905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.731119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:11:52.281702Z","time spent":"448.997975ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:11:52.731264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:11:52.290622Z","time spent":"440.307439ms","remote":"127.0.0.1:59754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:11:52.745066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.283291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745123Z","caller":"traceutil/trace.go:171","msg":"trace[711620872] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:584; }","duration":"289.377462ms","start":"2026-05-04T18:11:52.455732Z","end":"2026-05-04T18:11:52.745109Z","steps":["trace[711620872] 'agreement among raft nodes before linearized reading' (duration: 289.307891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.936737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745245Z","caller":"traceutil/trace.go:171","msg":"trace[1481051431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"173.980118ms","start":"2026-05-04T18:11:52.571256Z","end":"2026-05-04T18:11:52.745236Z","steps":["trace[1481051431] 'agreement among raft nodes before linearized reading' (duration: 173.937917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.465129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.745365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.124641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745389Z","caller":"traceutil/trace.go:171","msg":"trace[1658838247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"174.181961ms","start":"2026-05-04T18:11:52.5712Z","end":"2026-05-04T18:11:52.745382Z","steps":["trace[1658838247] 'agreement among raft nodes before linearized reading' (duration: 174.146271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.752085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.745495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.523249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745511Z","caller":"traceutil/trace.go:171","msg":"trace[1228380025] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:584; }","duration":"307.852408ms","start":"2026-05-04T18:11:52.437611Z","end":"2026-05-04T18:11:52.745463Z","steps":["trace[1228380025] 'agreement among raft nodes before linearized reading' (duration: 307.741036ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.745518Z","caller":"traceutil/trace.go:171","msg":"trace[716312512] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"197.650422ms","start":"2026-05-04T18:11:52.547862Z","end":"2026-05-04T18:11:52.745512Z","steps":["trace[716312512] 'agreement among raft nodes before linearized reading' (duration: 197.595801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.359161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.745389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.430948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.745405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.854111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745637Z","caller":"traceutil/trace.go:171","msg":"trace[446626081] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"173.649472ms","start":"2026-05-04T18:11:52.571937Z","end":"2026-05-04T18:11:52.745586Z","steps":["trace[446626081] 'agreement among raft nodes before linearized reading' (duration: 173.232224ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.745673Z","caller":"traceutil/trace.go:171","msg":"trace[1540390037] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"155.106396ms","start":"2026-05-04T18:11:52.590502Z","end":"2026-05-04T18:11:52.745608Z","steps":["trace[1540390037] 'agreement among raft nodes before linearized reading' (duration: 154.854051ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.74542Z","caller":"traceutil/trace.go:171","msg":"trace[1023383135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:584; }","duration":"307.579782ms","start":"2026-05-04T18:11:52.43771Z","end":"2026-05-04T18:11:52.745289Z","steps":["trace[1023383135] 'agreement among raft nodes before linearized reading' (duration: 307.4777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:11:52.437592Z","time spent":"307.953529ms","remote":"127.0.0.1:58762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T18:11:52.745767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:11:52.437686Z","time spent":"308.07232ms","remote":"127.0.0.1:58762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-04T18:11:52.74542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.725579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.745928Z","caller":"traceutil/trace.go:171","msg":"trace[1257620843] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"155.284001ms","start":"2026-05-04T18:11:52.590597Z","end":"2026-05-04T18:11:52.745881Z","steps":["trace[1257620843] 'agreement among raft nodes before linearized reading' (duration: 154.735099ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.745566Z","caller":"traceutil/trace.go:171","msg":"trace[285406872] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:584; }","duration":"290.461303ms","start":"2026-05-04T18:11:52.455093Z","end":"2026-05-04T18:11:52.745555Z","steps":["trace[285406872] 'agreement among raft nodes before linearized reading' (duration: 290.35237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.745323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.126777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.745065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.267385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.74602Z","caller":"traceutil/trace.go:171","msg":"trace[917801413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:584; }","duration":"290.859881ms","start":"2026-05-04T18:11:52.455147Z","end":"2026-05-04T18:11:52.746007Z","steps":["trace[917801413] 'agreement among raft nodes before linearized reading' (duration: 290.104336ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.74609Z","caller":"traceutil/trace.go:171","msg":"trace[1994010131] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"198.322615ms","start":"2026-05-04T18:11:52.547753Z","end":"2026-05-04T18:11:52.746075Z","steps":["trace[1994010131] 'agreement among raft nodes before linearized reading' (duration: 197.267584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:11:52.746223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.443874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:11:52.746237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.310735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:11:52.746285Z","caller":"traceutil/trace.go:171","msg":"trace[1815682423] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"198.394347ms","start":"2026-05-04T18:11:52.547879Z","end":"2026-05-04T18:11:52.746273Z","steps":["trace[1815682423] 'agreement among raft nodes before linearized reading' (duration: 197.672162ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:11:52.746287Z","caller":"traceutil/trace.go:171","msg":"trace[390312020] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"155.513045ms","start":"2026-05-04T18:11:52.590756Z","end":"2026-05-04T18:11:52.74627Z","steps":["trace[390312020] 'agreement among raft nodes before linearized reading' (duration: 154.7663ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:12:12.603815Z","caller":"traceutil/trace.go:171","msg":"trace[444036874] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"237.218861ms","start":"2026-05-04T18:12:12.366543Z","end":"2026-05-04T18:12:12.603762Z","steps":["trace[444036874] 'process raft request' (duration: 230.320699ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:12:40.498806Z","caller":"traceutil/trace.go:171","msg":"trace[2085158718] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"217.953509ms","start":"2026-05-04T18:12:40.280803Z","end":"2026-05-04T18:12:40.498757Z","steps":["trace[2085158718] 'process raft request' (duration: 217.754545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:13:28.903493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.177892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21831116-cfe3-4a18-9623-8b38d23cee2e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:13:28.903655Z","caller":"traceutil/trace.go:171","msg":"trace[1035411833] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21831116-cfe3-4a18-9623-8b38d23cee2e; range_end:; response_count:1; response_revision:1826; }","duration":"154.369855ms","start":"2026-05-04T18:13:28.74922Z","end":"2026-05-04T18:13:28.90359Z","steps":["trace[1035411833] 'range keys from in-memory index tree' (duration: 153.944837ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:13:28.903529Z","caller":"traceutil/trace.go:171","msg":"trace[725563248] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"100.397695ms","start":"2026-05-04T18:13:28.803107Z","end":"2026-05-04T18:13:28.903505Z","steps":["trace[725563248] 'read index received' (duration: 66.982378ms)","trace[725563248] 'applied index is now lower than readState.Index' (duration: 33.413837ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:13:28.90358Z","caller":"traceutil/trace.go:171","msg":"trace[1613671125] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"140.400589ms","start":"2026-05-04T18:13:28.76314Z","end":"2026-05-04T18:13:28.90354Z","steps":["trace[1613671125] 'process raft request' (duration: 107.034922ms)","trace[1613671125] 'compare' (duration: 33.124692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:13:28.903741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.61167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-04T18:13:28.903957Z","caller":"traceutil/trace.go:171","msg":"trace[1421551750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1827; }","duration":"100.829403ms","start":"2026-05-04T18:13:28.803067Z","end":"2026-05-04T18:13:28.903896Z","steps":["trace[1421551750] 'agreement among raft nodes before linearized reading' (duration: 100.513677ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:14:18.880491Z","caller":"traceutil/trace.go:171","msg":"trace[740592579] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"117.03217ms","start":"2026-05-04T18:14:18.763431Z","end":"2026-05-04T18:14:18.880463Z","steps":["trace[740592579] 'process raft request' (duration: 116.835196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:14:24.707477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.994053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:14:24.707621Z","caller":"traceutil/trace.go:171","msg":"trace[1500355759] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"138.071104ms","start":"2026-05-04T18:14:24.569527Z","end":"2026-05-04T18:14:24.707598Z","steps":["trace[1500355759] 'read index received' (duration: 31.54148ms)","trace[1500355759] 'applied index is now lower than readState.Index' (duration: 106.527544ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:14:24.707675Z","caller":"traceutil/trace.go:171","msg":"trace[335153067] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"169.310218ms","start":"2026-05-04T18:14:24.538323Z","end":"2026-05-04T18:14:24.707633Z","steps":["trace[335153067] 'process raft request' (duration: 62.758324ms)","trace[335153067] 'compare' (duration: 105.861131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:14:24.707724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.191956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:14:24.707773Z","caller":"traceutil/trace.go:171","msg":"trace[1051917035] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2178; }","duration":"138.269847ms","start":"2026-05-04T18:14:24.569488Z","end":"2026-05-04T18:14:24.707758Z","steps":["trace[1051917035] 'agreement among raft nodes before linearized reading' (duration: 138.180545ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:14:24.902226Z","caller":"traceutil/trace.go:171","msg":"trace[1729611510] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2267; }","duration":"127.662069ms","start":"2026-05-04T18:14:24.774538Z","end":"2026-05-04T18:14:24.9022Z","steps":["trace[1729611510] 'read index received' (duration: 127.529847ms)","trace[1729611510] 'applied index is now lower than readState.Index' (duration: 131.372µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:14:24.902364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.810903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:14:24.902402Z","caller":"traceutil/trace.go:171","msg":"trace[1333760770] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2179; }","duration":"127.912365ms","start":"2026-05-04T18:14:24.77448Z","end":"2026-05-04T18:14:24.902392Z","steps":["trace[1333760770] 'agreement among raft nodes before linearized reading' (duration: 127.819833ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:14:24.90258Z","caller":"traceutil/trace.go:171","msg":"trace[211796158] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"200.34956ms","start":"2026-05-04T18:14:24.702204Z","end":"2026-05-04T18:14:24.902553Z","steps":["trace[211796158] 'process raft request' (duration: 199.860661ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:14:58.986144Z","caller":"traceutil/trace.go:171","msg":"trace[1401621268] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"112.007466ms","start":"2026-05-04T18:14:58.874118Z","end":"2026-05-04T18:14:58.986125Z","steps":["trace[1401621268] 'process raft request' (duration: 111.881664ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:15:27.264342Z","caller":"traceutil/trace.go:171","msg":"trace[1592041420] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"105.402242ms","start":"2026-05-04T18:15:27.158771Z","end":"2026-05-04T18:15:27.264173Z","steps":["trace[1592041420] 'process raft request' (duration: 90.780517ms)","trace[1592041420] 'compare' (duration: 14.239018ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:15:27.275106Z","caller":"traceutil/trace.go:171","msg":"trace[97699228] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"114.265838ms","start":"2026-05-04T18:15:27.160814Z","end":"2026-05-04T18:15:27.27508Z","steps":["trace[97699228] 'process raft request' (duration: 114.146996ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:15:33.573409Z","caller":"traceutil/trace.go:171","msg":"trace[1413521945] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"395.406902ms","start":"2026-05-04T18:15:33.17797Z","end":"2026-05-04T18:15:33.573377Z","steps":["trace[1413521945] 'process raft request' (duration: 348.99469ms)","trace[1413521945] 'compare' (duration: 46.013694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:15:33.573564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:15:33.177951Z","time spent":"395.544424ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:15:33.632639Z","caller":"traceutil/trace.go:171","msg":"trace[1948311639] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"350.166652ms","start":"2026-05-04T18:15:33.282447Z","end":"2026-05-04T18:15:33.632614Z","steps":["trace[1948311639] 'process raft request' (duration: 350.028439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:15:33.632779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:15:33.282424Z","time spent":"350.289684ms","remote":"127.0.0.1:59836","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-05-04T18:15:33.63301Z","caller":"traceutil/trace.go:171","msg":"trace[407462169] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"338.674135ms","start":"2026-05-04T18:15:33.294324Z","end":"2026-05-04T18:15:33.632999Z","steps":["trace[407462169] 'process raft request' (duration: 338.261287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:15:33.633097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:15:33.294298Z","time spent":"338.768156ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:18:09.240471Z","caller":"traceutil/trace.go:171","msg":"trace[658006827] transaction","detail":"{read_only:false; response_revision:3772; number_of_response:1; }","duration":"107.022141ms","start":"2026-05-04T18:18:09.133414Z","end":"2026-05-04T18:18:09.240436Z","steps":["trace[658006827] 'process raft request' (duration: 106.770247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:19:35.904436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.152524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ddf3a433-9996-4fe7-82d5-19a4ea4e9851\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-05-04T18:19:35.904516Z","caller":"traceutil/trace.go:171","msg":"trace[707745146] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ddf3a433-9996-4fe7-82d5-19a4ea4e9851; range_end:; response_count:1; response_revision:4814; }","duration":"114.253876ms","start":"2026-05-04T18:19:35.790246Z","end":"2026-05-04T18:19:35.904499Z","steps":["trace[707745146] 'range keys from in-memory index tree' (duration: 113.94081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:19:39.409706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.899143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-04T18:19:39.409906Z","caller":"traceutil/trace.go:171","msg":"trace[1127951587] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"153.403035ms","start":"2026-05-04T18:19:39.256484Z","end":"2026-05-04T18:19:39.409887Z","steps":["trace[1127951587] 'read index received' (duration: 13.252807ms)","trace[1127951587] 'applied index is now lower than readState.Index' (duration: 140.148538ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:19:39.409914Z","caller":"traceutil/trace.go:171","msg":"trace[154438625] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4969; }","duration":"261.484155ms","start":"2026-05-04T18:19:39.148377Z","end":"2026-05-04T18:19:39.409861Z","steps":["trace[154438625] 'process raft request' (duration: 121.332897ms)","trace[154438625] 'compare' (duration: 139.856833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:19:39.410086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.593338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8615dc8-366b-4275-ad70-bd01d0bcc9b3\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-04T18:19:39.410123Z","caller":"traceutil/trace.go:171","msg":"trace[1260935214] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8615dc8-366b-4275-ad70-bd01d0bcc9b3; range_end:; response_count:1; response_revision:4969; }","duration":"153.68479ms","start":"2026-05-04T18:19:39.256429Z","end":"2026-05-04T18:19:39.410114Z","steps":["trace[1260935214] 'agreement among raft nodes before linearized reading' (duration: 153.515217ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:19:39.707465Z","caller":"traceutil/trace.go:171","msg":"trace[1341341093] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"125.129438ms","start":"2026-05-04T18:19:39.58231Z","end":"2026-05-04T18:19:39.70744Z","steps":["trace[1341341093] 'process raft request' (duration: 110.953344ms)","trace[1341341093] 'compare' (duration: 13.9476ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:19:52.714186Z","caller":"traceutil/trace.go:171","msg":"trace[1728197070] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"133.112696ms","start":"2026-05-04T18:19:52.58103Z","end":"2026-05-04T18:19:52.714143Z","steps":["trace[1728197070] 'process raft request' (duration: 133.084726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:19:52.714222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.916103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h8jdm\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-04T18:19:52.714293Z","caller":"traceutil/trace.go:171","msg":"trace[938091419] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h8jdm; range_end:; response_count:1; response_revision:5227; }","duration":"204.024295ms","start":"2026-05-04T18:19:52.510249Z","end":"2026-05-04T18:19:52.714274Z","steps":["trace[938091419] 'range keys from in-memory index tree' (duration: 203.7054ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:19:52.714554Z","caller":"traceutil/trace.go:171","msg":"trace[537761863] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"144.114462ms","start":"2026-05-04T18:19:52.570433Z","end":"2026-05-04T18:19:52.714547Z","steps":["trace[537761863] 'process raft request' (duration: 114.692824ms)","trace[537761863] 'compare' (duration: 28.860777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:20:08.310845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.037752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84f11061-d48b-4928-b78e-cf83c67f69e3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-04T18:20:08.310941Z","caller":"traceutil/trace.go:171","msg":"trace[626566240] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84f11061-d48b-4928-b78e-cf83c67f69e3; range_end:; response_count:1; response_revision:5508; }","duration":"310.190925ms","start":"2026-05-04T18:20:08.000729Z","end":"2026-05-04T18:20:08.31092Z","steps":["trace[626566240] 'range keys from in-memory index tree' (duration: 309.840688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:20:08.311004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:20:08.000705Z","time spent":"310.288476ms","remote":"127.0.0.1:59754","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-84f11061-d48b-4928-b78e-cf83c67f69e3\" "} {"level":"warn","ts":"2026-05-04T18:20:08.311146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.791408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:20:08.311253Z","caller":"traceutil/trace.go:171","msg":"trace[81639601] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5508; }","duration":"117.972301ms","start":"2026-05-04T18:20:08.193252Z","end":"2026-05-04T18:20:08.311224Z","steps":["trace[81639601] 'count revisions from in-memory index tree' (duration: 117.704746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:20:08.311269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.106398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:20:08.311311Z","caller":"traceutil/trace.go:171","msg":"trace[476793844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5508; }","duration":"298.150399ms","start":"2026-05-04T18:20:08.013147Z","end":"2026-05-04T18:20:08.311297Z","steps":["trace[476793844] 'range keys from in-memory index tree' (duration: 298.015506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:20:08.311337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.919371ms","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-05-04T18:20:08.311411Z","caller":"traceutil/trace.go:171","msg":"trace[894778772] 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:5508; }","duration":"285.037283ms","start":"2026-05-04T18:20:08.02634Z","end":"2026-05-04T18:20:08.311377Z","steps":["trace[894778772] 'range keys from in-memory index tree' (duration: 284.704177ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:21:13.909721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-05-04T18:21:13.979556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"68.282209ms","hash":2779118435,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-04T18:21:13.97962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2779118435,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-05-04T18:26:13.916146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6048} {"level":"info","ts":"2026-05-04T18:26:14.025479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6048,"took":"106.303369ms","hash":428146318,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":23789568,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T18:26:14.025566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":428146318,"revision":6048,"compact-revision":2970} {"level":"warn","ts":"2026-05-04T18:26:16.419901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.598161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:26:16.420013Z","caller":"traceutil/trace.go:171","msg":"trace[690269449] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"243.032207ms","start":"2026-05-04T18:26:16.176963Z","end":"2026-05-04T18:26:16.419995Z","steps":["trace[690269449] 'process raft request' (duration: 94.235173ms)","trace[690269449] 'compare' (duration: 148.428647ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:26:16.438885Z","caller":"traceutil/trace.go:171","msg":"trace[218197345] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"261.654796ms","start":"2026-05-04T18:26:16.177205Z","end":"2026-05-04T18:26:16.43886Z","steps":["trace[218197345] 'process raft request' (duration: 261.454622ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:26:16.439108Z","caller":"traceutil/trace.go:171","msg":"trace[208792659] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"241.768514ms","start":"2026-05-04T18:26:16.197309Z","end":"2026-05-04T18:26:16.439078Z","steps":["trace[208792659] 'process raft request' (duration: 241.507049ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:26:28.646417Z","caller":"traceutil/trace.go:171","msg":"trace[629622480] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"185.759676ms","start":"2026-05-04T18:26:28.460635Z","end":"2026-05-04T18:26:28.646394Z","steps":["trace[629622480] 'process raft request' (duration: 185.580313ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:26:28.656119Z","caller":"traceutil/trace.go:171","msg":"trace[697779325] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"170.432819ms","start":"2026-05-04T18:26:28.48566Z","end":"2026-05-04T18:26:28.656093Z","steps":["trace[697779325] 'process raft request' (duration: 170.232594ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:26:29.5913Z","caller":"traceutil/trace.go:171","msg":"trace[1749419170] linearizableReadLoop","detail":"{readStateIndex:8653; appliedIndex:8652; }","duration":"220.371532ms","start":"2026-05-04T18:26:29.370901Z","end":"2026-05-04T18:26:29.591273Z","steps":["trace[1749419170] 'read index received' (duration: 191.318579ms)","trace[1749419170] 'applied index is now lower than readState.Index' (duration: 29.051323ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:26:29.591463Z","caller":"traceutil/trace.go:171","msg":"trace[670398720] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"232.600413ms","start":"2026-05-04T18:26:29.358806Z","end":"2026-05-04T18:26:29.591407Z","steps":["trace[670398720] 'process raft request' (duration: 203.492028ms)","trace[670398720] 'compare' (duration: 28.693557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:26:29.591505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.596527ms","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-05-04T18:26:29.591574Z","caller":"traceutil/trace.go:171","msg":"trace[6974133] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8349; }","duration":"220.698299ms","start":"2026-05-04T18:26:29.370842Z","end":"2026-05-04T18:26:29.59154Z","steps":["trace[6974133] 'agreement among raft nodes before linearized reading' (duration: 220.523016ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:26:57.331844Z","caller":"traceutil/trace.go:171","msg":"trace[46353393] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8859; }","duration":"144.588606ms","start":"2026-05-04T18:26:57.187232Z","end":"2026-05-04T18:26:57.33182Z","steps":["trace[46353393] 'read index received' (duration: 57.089089ms)","trace[46353393] 'applied index is now lower than readState.Index' (duration: 87.498227ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:26:57.331978Z","caller":"traceutil/trace.go:171","msg":"trace[262784164] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"183.7914ms","start":"2026-05-04T18:26:57.14816Z","end":"2026-05-04T18:26:57.331952Z","steps":["trace[262784164] 'process raft request' (duration: 183.496364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:26:57.332056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.81929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h8jdm\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-04T18:26:57.332095Z","caller":"traceutil/trace.go:171","msg":"trace[952292157] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h8jdm; range_end:; response_count:1; response_revision:8549; }","duration":"144.903912ms","start":"2026-05-04T18:26:57.187179Z","end":"2026-05-04T18:26:57.332083Z","steps":["trace[952292157] 'agreement among raft nodes before linearized reading' (duration: 144.732229ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:27:00.87921Z","caller":"traceutil/trace.go:171","msg":"trace[1462307950] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"105.737308ms","start":"2026-05-04T18:27:00.773435Z","end":"2026-05-04T18:27:00.879172Z","steps":["trace[1462307950] 'process raft request' (duration: 105.335061ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:27:00.879401Z","caller":"traceutil/trace.go:171","msg":"trace[361900902] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"101.54892ms","start":"2026-05-04T18:27:00.777804Z","end":"2026-05-04T18:27:00.879352Z","steps":["trace[361900902] 'process raft request' (duration: 101.184623ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:27:11.138171Z","caller":"traceutil/trace.go:171","msg":"trace[1106616689] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"232.706405ms","start":"2026-05-04T18:27:10.905402Z","end":"2026-05-04T18:27:11.138108Z","steps":["trace[1106616689] 'process raft request' (duration: 173.000398ms)","trace[1106616689] 'compare' (duration: 59.265579ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:27:11.138542Z","caller":"traceutil/trace.go:171","msg":"trace[268965637] linearizableReadLoop","detail":"{readStateIndex:8989; appliedIndex:8987; }","duration":"149.940735ms","start":"2026-05-04T18:27:10.988576Z","end":"2026-05-04T18:27:11.138517Z","steps":["trace[268965637] 'read index received' (duration: 89.690898ms)","trace[268965637] 'applied index is now lower than readState.Index' (duration: 60.248217ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:27:11.138676Z","caller":"traceutil/trace.go:171","msg":"trace[616415340] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"208.698406ms","start":"2026-05-04T18:27:10.929962Z","end":"2026-05-04T18:27:11.138661Z","steps":["trace[616415340] 'process raft request' (duration: 208.318888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:27:11.138982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.368274ms","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-05-04T18:27:11.139046Z","caller":"traceutil/trace.go:171","msg":"trace[1172789420] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8671; }","duration":"150.475556ms","start":"2026-05-04T18:27:10.98855Z","end":"2026-05-04T18:27:11.139026Z","steps":["trace[1172789420] 'agreement among raft nodes before linearized reading' (duration: 150.233991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:27:11.139224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.581261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:27:11.1393Z","caller":"traceutil/trace.go:171","msg":"trace[69834695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8671; }","duration":"125.734533ms","start":"2026-05-04T18:27:11.013549Z","end":"2026-05-04T18:27:11.139284Z","steps":["trace[69834695] 'agreement among raft nodes before linearized reading' (duration: 125.577561ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:27:27.764161Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c5.wal"} {"level":"warn","ts":"2026-05-04T18:28:26.841605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.251507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-04T18:28:26.841701Z","caller":"traceutil/trace.go:171","msg":"trace[1618585430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9408; }","duration":"167.40655ms","start":"2026-05-04T18:28:26.674274Z","end":"2026-05-04T18:28:26.841681Z","steps":["trace[1618585430] 'range keys from in-memory index tree' (duration: 167.028793ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:29:01.942908Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T18:29:01.948078Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-04T18:29:01.948224Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-04T18:29:05.646345Z","caller":"traceutil/trace.go:171","msg":"trace[1402821434] transaction","detail":"{read_only:false; response_revision:9692; number_of_response:1; }","duration":"115.884595ms","start":"2026-05-04T18:29:05.530438Z","end":"2026-05-04T18:29:05.646323Z","steps":["trace[1402821434] 'process raft request' (duration: 115.712382ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:29:05.646309Z","caller":"traceutil/trace.go:171","msg":"trace[39138253] linearizableReadLoop","detail":"{readStateIndex:10038; appliedIndex:10036; }","duration":"109.510248ms","start":"2026-05-04T18:29:05.536763Z","end":"2026-05-04T18:29:05.646273Z","steps":["trace[39138253] 'read index received' (duration: 91.23884ms)","trace[39138253] 'applied index is now lower than readState.Index' (duration: 18.269748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:29:05.64649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.686411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:29:05.646541Z","caller":"traceutil/trace.go:171","msg":"trace[88531991] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:9692; }","duration":"109.796683ms","start":"2026-05-04T18:29:05.536731Z","end":"2026-05-04T18:29:05.646528Z","steps":["trace[88531991] 'agreement among raft nodes before linearized reading' (duration: 109.65086ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:31:13.922827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-05-04T18:31:14.001246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"76.297441ms","hash":896942249,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":24903680,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-04T18:31:14.00133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":896942249,"revision":8266,"compact-revision":6048} {"level":"info","ts":"2026-05-04T18:34:06.961464Z","caller":"traceutil/trace.go:171","msg":"trace[378583846] transaction","detail":"{read_only:false; response_revision:11855; number_of_response:1; }","duration":"106.897884ms","start":"2026-05-04T18:34:06.854534Z","end":"2026-05-04T18:34:06.961432Z","steps":["trace[378583846] 'process raft request' (duration: 106.670319ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:36:13.928955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10490} {"level":"info","ts":"2026-05-04T18:36:14.009878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10490,"took":"78.536883ms","hash":1680543336,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":25886720,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-04T18:36:14.009955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1680543336,"revision":10490,"compact-revision":8266} {"level":"info","ts":"2026-05-04T18:41:13.935464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12717} {"level":"info","ts":"2026-05-04T18:41:14.013784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12717,"took":"75.623687ms","hash":571017981,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":26783744,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-04T18:41:14.013899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":571017981,"revision":12717,"compact-revision":10490} {"level":"info","ts":"2026-05-04T18:46:13.941737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14643} {"level":"info","ts":"2026-05-04T18:46:14.015229Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14643,"took":"70.875923ms","hash":2571788986,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":22151168,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-04T18:46:14.015282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2571788986,"revision":14643,"compact-revision":12717} {"level":"info","ts":"2026-05-04T18:51:13.950137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16168} {"level":"info","ts":"2026-05-04T18:51:14.018522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16168,"took":"65.683779ms","hash":2787313871,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20471808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-04T18:51:14.018598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2787313871,"revision":16168,"compact-revision":14643} {"level":"info","ts":"2026-05-04T18:56:13.957045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17689} {"level":"info","ts":"2026-05-04T18:56:14.031131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17689,"took":"69.948289ms","hash":3054063360,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20897792,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T18:56:14.031209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3054063360,"revision":17689,"compact-revision":16168} {"level":"info","ts":"2026-05-04T18:56:30.056084Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T18:56:30.061478Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-04T18:56:30.061611Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-04T19:01:13.962996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19213} {"level":"info","ts":"2026-05-04T19:01:14.029612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19213,"took":"64.166663ms","hash":370575311,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20606976,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:01:14.029691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":370575311,"revision":19213,"compact-revision":17689} {"level":"info","ts":"2026-05-04T19:06:13.969434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20756} {"level":"info","ts":"2026-05-04T19:06:14.038797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20756,"took":"66.663661ms","hash":1373033559,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20758528,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:06:14.038872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1373033559,"revision":20756,"compact-revision":19213} {"level":"info","ts":"2026-05-04T19:11:13.975761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22312} {"level":"info","ts":"2026-05-04T19:11:14.040605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22312,"took":"62.454128ms","hash":218688309,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20762624,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:11:14.040648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":218688309,"revision":22312,"compact-revision":20756} {"level":"info","ts":"2026-05-04T19:16:13.984811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23833} {"level":"info","ts":"2026-05-04T19:16:14.055764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23833,"took":"67.448864ms","hash":3065612806,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":20766720,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:16:14.055853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065612806,"revision":23833,"compact-revision":22312} {"level":"info","ts":"2026-05-04T19:18:07.686741Z","caller":"traceutil/trace.go:171","msg":"trace[978828922] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"114.059708ms","start":"2026-05-04T19:18:07.572654Z","end":"2026-05-04T19:18:07.686714Z","steps":["trace[978828922] 'process raft request' (duration: 113.868924ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:18:16.901667Z","caller":"traceutil/trace.go:171","msg":"trace[714358944] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"131.310291ms","start":"2026-05-04T19:18:16.770323Z","end":"2026-05-04T19:18:16.901633Z","steps":["trace[714358944] 'process raft request' (duration: 131.083677ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:18:16.903706Z","caller":"traceutil/trace.go:171","msg":"trace[2039051540] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"131.261198ms","start":"2026-05-04T19:18:16.772416Z","end":"2026-05-04T19:18:16.903678Z","steps":["trace[2039051540] 'process raft request' (duration: 130.570536ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:18:16.903901Z","caller":"traceutil/trace.go:171","msg":"trace[417286268] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"126.179314ms","start":"2026-05-04T19:18:16.777441Z","end":"2026-05-04T19:18:16.903621Z","steps":["trace[417286268] 'process raft request' (duration: 126.118223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:18:25.487566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.770473ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-04T19:18:25.487639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.819865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:18:25.4877Z","caller":"traceutil/trace.go:171","msg":"trace[1662357655] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26071; }","duration":"303.920416ms","start":"2026-05-04T19:18:25.183757Z","end":"2026-05-04T19:18:25.487677Z","steps":["trace[1662357655] 'range keys from in-memory index tree' (duration: 303.744263ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:18:25.487892Z","caller":"traceutil/trace.go:171","msg":"trace[1546996939] linearizableReadLoop","detail":"{readStateIndex:27057; appliedIndex:27056; }","duration":"225.815853ms","start":"2026-05-04T19:18:25.262068Z","end":"2026-05-04T19:18:25.487884Z","steps":["trace[1546996939] 'read index received' (duration: 48.622041ms)","trace[1546996939] 'applied index is now lower than readState.Index' (duration: 177.192962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:18:25.488036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.963405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h8jdm\" ","response":"range_response_count:1 size:6452"} {"level":"info","ts":"2026-05-04T19:18:25.488071Z","caller":"traceutil/trace.go:171","msg":"trace[16388146] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h8jdm; range_end:; response_count:1; response_revision:26073; }","duration":"226.025256ms","start":"2026-05-04T19:18:25.262033Z","end":"2026-05-04T19:18:25.488059Z","steps":["trace[16388146] 'agreement among raft nodes before linearized reading' (duration: 225.883903ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:18:25.488054Z","caller":"traceutil/trace.go:171","msg":"trace[162177661] transaction","detail":"{read_only:false; response_revision:26072; number_of_response:1; }","duration":"423.17709ms","start":"2026-05-04T19:18:25.064834Z","end":"2026-05-04T19:18:25.488012Z","steps":["trace[162177661] 'process raft request' (duration: 245.914777ms)","trace[162177661] 'compare' (duration: 176.5851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:18:25.488201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:18:25.064814Z","time spent":"423.310393ms","remote":"127.0.0.1:59754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:19:40.383529Z","caller":"traceutil/trace.go:171","msg":"trace[302412306] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"127.065071ms","start":"2026-05-04T19:19:40.25643Z","end":"2026-05-04T19:19:40.383495Z","steps":["trace[302412306] 'process raft request' (duration: 94.546412ms)","trace[302412306] 'compare' (duration: 32.257384ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:20:14.688617Z","caller":"traceutil/trace.go:171","msg":"trace[887079347] transaction","detail":"{read_only:false; response_revision:27197; number_of_response:1; }","duration":"121.348174ms","start":"2026-05-04T19:20:14.56721Z","end":"2026-05-04T19:20:14.688558Z","steps":["trace[887079347] 'process raft request' (duration: 114.376313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:20:14.689579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.576376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:77 size:739247"} {"level":"info","ts":"2026-05-04T19:20:14.689689Z","caller":"traceutil/trace.go:171","msg":"trace[1699447365] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:77; response_revision:27197; }","duration":"115.701409ms","start":"2026-05-04T19:20:14.573967Z","end":"2026-05-04T19:20:14.689668Z","steps":["trace[1699447365] 'agreement among raft nodes before linearized reading' (duration: 114.67583ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:20:14.688591Z","caller":"traceutil/trace.go:171","msg":"trace[1533449348] linearizableReadLoop","detail":"{readStateIndex:28216; appliedIndex:28215; }","duration":"114.526026ms","start":"2026-05-04T19:20:14.573997Z","end":"2026-05-04T19:20:14.688523Z","steps":["trace[1533449348] 'read index received' (duration: 107.606006ms)","trace[1533449348] 'applied index is now lower than readState.Index' (duration: 6.91819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:20:14.692645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.143804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5451"} {"level":"info","ts":"2026-05-04T19:20:14.692703Z","caller":"traceutil/trace.go:171","msg":"trace[1212034677] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:27197; }","duration":"118.188215ms","start":"2026-05-04T19:20:14.574487Z","end":"2026-05-04T19:20:14.692675Z","steps":["trace[1212034677] 'agreement among raft nodes before linearized reading' (duration: 118.071343ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:20:15.536458Z","caller":"traceutil/trace.go:171","msg":"trace[1559370506] transaction","detail":"{read_only:false; response_revision:27202; number_of_response:1; }","duration":"161.593669ms","start":"2026-05-04T19:20:15.374839Z","end":"2026-05-04T19:20:15.536433Z","steps":["trace[1559370506] 'process raft request' (duration: 161.140399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:20:15.75682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.647397ms","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:6"} {"level":"info","ts":"2026-05-04T19:20:15.756968Z","caller":"traceutil/trace.go:171","msg":"trace[1984323189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:27202; }","duration":"131.861511ms","start":"2026-05-04T19:20:15.625079Z","end":"2026-05-04T19:20:15.75694Z","steps":["trace[1984323189] 'count revisions from in-memory index tree' (duration: 131.520555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:20:15.757048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.657356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-04T19:20:15.757157Z","caller":"traceutil/trace.go:171","msg":"trace[1095427841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27202; }","duration":"195.82111ms","start":"2026-05-04T19:20:15.561306Z","end":"2026-05-04T19:20:15.757127Z","steps":["trace[1095427841] 'range keys from in-memory index tree' (duration: 195.455872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:20:15.757512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.888861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-04T19:20:15.757566Z","caller":"traceutil/trace.go:171","msg":"trace[943921425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27202; }","duration":"188.961692ms","start":"2026-05-04T19:20:15.56859Z","end":"2026-05-04T19:20:15.757552Z","steps":["trace[943921425] 'range keys from in-memory index tree' (duration: 188.716487ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:21:13.991766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25387} {"level":"info","ts":"2026-05-04T19:21:14.08318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25387,"took":"89.214122ms","hash":1112202645,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":25120768,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-04T19:21:14.083301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1112202645,"revision":25387,"compact-revision":23833} {"level":"info","ts":"2026-05-04T19:23:59.905022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T19:23:59.910517Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-04T19:23:59.910794Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-04T19:26:10.010742Z","caller":"traceutil/trace.go:171","msg":"trace[804556859] transaction","detail":"{read_only:false; response_revision:29823; number_of_response:1; }","duration":"133.764668ms","start":"2026-05-04T19:26:09.876957Z","end":"2026-05-04T19:26:10.010722Z","steps":["trace[804556859] 'process raft request' (duration: 133.612375ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:26:14.005999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27566} {"level":"info","ts":"2026-05-04T19:26:14.121302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27566,"took":"108.396411ms","hash":150090774,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-04T19:26:14.121353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":150090774,"revision":27566,"compact-revision":25387} {"level":"info","ts":"2026-05-04T19:26:16.54371Z","caller":"traceutil/trace.go:171","msg":"trace[2095650054] transaction","detail":"{read_only:false; response_revision:29859; number_of_response:1; }","duration":"273.684229ms","start":"2026-05-04T19:26:16.269997Z","end":"2026-05-04T19:26:16.543682Z","steps":["trace[2095650054] 'process raft request' (duration: 273.520286ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:26:16.801448Z","caller":"traceutil/trace.go:171","msg":"trace[2027223413] linearizableReadLoop","detail":"{readStateIndex:30968; appliedIndex:30967; }","duration":"198.318116ms","start":"2026-05-04T19:26:16.603107Z","end":"2026-05-04T19:26:16.801425Z","steps":["trace[2027223413] 'read index received' (duration: 198.116163ms)","trace[2027223413] 'applied index is now lower than readState.Index' (duration: 201.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:26:16.801544Z","caller":"traceutil/trace.go:171","msg":"trace[1618091562] transaction","detail":"{read_only:false; response_revision:29860; number_of_response:1; }","duration":"253.333948ms","start":"2026-05-04T19:26:16.548165Z","end":"2026-05-04T19:26:16.801499Z","steps":["trace[1618091562] 'process raft request' (duration: 253.123764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:26:16.801757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.636693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-04T19:26:16.801806Z","caller":"traceutil/trace.go:171","msg":"trace[665974050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29860; }","duration":"198.730294ms","start":"2026-05-04T19:26:16.603064Z","end":"2026-05-04T19:26:16.801794Z","steps":["trace[665974050] 'agreement among raft nodes before linearized reading' (duration: 198.50915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:26:16.801872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.232446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:26:16.801947Z","caller":"traceutil/trace.go:171","msg":"trace[1485265464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29860; }","duration":"197.392379ms","start":"2026-05-04T19:26:16.604536Z","end":"2026-05-04T19:26:16.801928Z","steps":["trace[1485265464] 'agreement among raft nodes before linearized reading' (duration: 197.145864ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:26:16.999845Z","caller":"traceutil/trace.go:171","msg":"trace[384417968] transaction","detail":"{read_only:false; response_revision:29862; number_of_response:1; }","duration":"192.64528ms","start":"2026-05-04T19:26:16.807168Z","end":"2026-05-04T19:26:16.999813Z","steps":["trace[384417968] 'process raft request' (duration: 161.984476ms)","trace[384417968] 'compare' (duration: 30.223406ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:26:24.821813Z","caller":"traceutil/trace.go:171","msg":"trace[575954508] transaction","detail":"{read_only:false; response_revision:29989; number_of_response:1; }","duration":"115.808679ms","start":"2026-05-04T19:26:24.705974Z","end":"2026-05-04T19:26:24.821783Z","steps":["trace[575954508] 'process raft request' (duration: 61.028573ms)","trace[575954508] 'compare' (duration: 54.586193ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:31:14.013109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29843} {"level":"info","ts":"2026-05-04T19:31:14.123754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29843,"took":"105.893485ms","hash":239289497,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-04T19:31:14.123828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":239289497,"revision":29843,"compact-revision":27566} {"level":"info","ts":"2026-05-04T19:32:14.350498Z","caller":"traceutil/trace.go:171","msg":"trace[1117118707] transaction","detail":"{read_only:false; response_revision:32629; number_of_response:1; }","duration":"151.969512ms","start":"2026-05-04T19:32:14.198501Z","end":"2026-05-04T19:32:14.35047Z","steps":["trace[1117118707] 'process raft request' (duration: 151.808099ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:32:14.531122Z","caller":"traceutil/trace.go:171","msg":"trace[1015238570] transaction","detail":"{read_only:false; response_revision:32630; number_of_response:1; }","duration":"138.51649ms","start":"2026-05-04T19:32:14.392567Z","end":"2026-05-04T19:32:14.531084Z","steps":["trace[1015238570] 'process raft request' (duration: 130.892276ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:32:15.057962Z","caller":"traceutil/trace.go:171","msg":"trace[233041963] transaction","detail":"{read_only:false; response_revision:32631; number_of_response:1; }","duration":"163.995409ms","start":"2026-05-04T19:32:14.89393Z","end":"2026-05-04T19:32:15.057925Z","steps":["trace[233041963] 'process raft request' (duration: 163.713984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:32:15.292637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.756968ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:32:15.292767Z","caller":"traceutil/trace.go:171","msg":"trace[642410589] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:32633; }","duration":"108.911271ms","start":"2026-05-04T19:32:15.183838Z","end":"2026-05-04T19:32:15.292749Z","steps":["trace[642410589] 'range keys from in-memory index tree' (duration: 108.734767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:32:15.293053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.90069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-04T19:32:15.293146Z","caller":"traceutil/trace.go:171","msg":"trace[2036641492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:32633; }","duration":"145.035293ms","start":"2026-05-04T19:32:15.148091Z","end":"2026-05-04T19:32:15.293126Z","steps":["trace[2036641492] 'range keys from in-memory index tree' (duration: 144.584274ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:32:15.741439Z","caller":"traceutil/trace.go:171","msg":"trace[1276995815] linearizableReadLoop","detail":"{readStateIndex:33838; appliedIndex:33836; }","duration":"130.781864ms","start":"2026-05-04T19:32:15.610628Z","end":"2026-05-04T19:32:15.74141Z","steps":["trace[1276995815] 'read index received' (duration: 4.76661ms)","trace[1276995815] 'applied index is now lower than readState.Index' (duration: 126.013744ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:32:15.741676Z","caller":"traceutil/trace.go:171","msg":"trace[1151169040] transaction","detail":"{read_only:false; response_revision:32636; number_of_response:1; }","duration":"134.191508ms","start":"2026-05-04T19:32:15.607413Z","end":"2026-05-04T19:32:15.741604Z","steps":["trace[1151169040] 'process raft request' (duration: 116.608386ms)","trace[1151169040] 'compare' (duration: 17.247715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:32:15.741805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.159131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-04T19:32:15.741881Z","caller":"traceutil/trace.go:171","msg":"trace[112135830] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:32636; }","duration":"131.292543ms","start":"2026-05-04T19:32:15.610576Z","end":"2026-05-04T19:32:15.741868Z","steps":["trace[112135830] 'agreement among raft nodes before linearized reading' (duration: 131.043479ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:32:15.75285Z","caller":"traceutil/trace.go:171","msg":"trace[126221431] transaction","detail":"{read_only:false; response_revision:32637; number_of_response:1; }","duration":"108.910172ms","start":"2026-05-04T19:32:15.643895Z","end":"2026-05-04T19:32:15.752805Z","steps":["trace[126221431] 'process raft request' (duration: 108.736738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:32:15.75292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.380724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:124876"} {"level":"info","ts":"2026-05-04T19:32:15.752979Z","caller":"traceutil/trace.go:171","msg":"trace[441054051] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:32637; }","duration":"141.487345ms","start":"2026-05-04T19:32:15.611477Z","end":"2026-05-04T19:32:15.752964Z","steps":["trace[441054051] 'agreement among raft nodes before linearized reading' (duration: 141.249221ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:36:02.190458Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008d49.wal"} {"level":"info","ts":"2026-05-04T19:36:08.608176Z","caller":"traceutil/trace.go:171","msg":"trace[2082031936] linearizableReadLoop","detail":"{readStateIndex:36340; appliedIndex:36339; }","duration":"220.200948ms","start":"2026-05-04T19:36:08.387949Z","end":"2026-05-04T19:36:08.60815Z","steps":["trace[2082031936] 'read index received' (duration: 179.41529ms)","trace[2082031936] 'applied index is now lower than readState.Index' (duration: 40.783768ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:36:08.608308Z","caller":"traceutil/trace.go:171","msg":"trace[274093573] transaction","detail":"{read_only:false; response_revision:35066; number_of_response:1; }","duration":"246.025335ms","start":"2026-05-04T19:36:08.36227Z","end":"2026-05-04T19:36:08.608296Z","steps":["trace[274093573] 'process raft request' (duration: 205.025163ms)","trace[274093573] 'compare' (duration: 40.720647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:36:08.608526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.539264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T19:36:08.608669Z","caller":"traceutil/trace.go:171","msg":"trace[1405624707] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:35066; }","duration":"220.717068ms","start":"2026-05-04T19:36:08.387909Z","end":"2026-05-04T19:36:08.608626Z","steps":["trace[1405624707] 'agreement among raft nodes before linearized reading' (duration: 220.494894ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:36:08.629444Z","caller":"traceutil/trace.go:171","msg":"trace[1276036266] transaction","detail":"{read_only:false; response_revision:35067; number_of_response:1; }","duration":"145.346687ms","start":"2026-05-04T19:36:08.484067Z","end":"2026-05-04T19:36:08.629413Z","steps":["trace[1276036266] 'process raft request' (duration: 145.114243ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:36:08.629818Z","caller":"traceutil/trace.go:171","msg":"trace[1558966589] transaction","detail":"{read_only:false; response_revision:35068; number_of_response:1; }","duration":"100.774409ms","start":"2026-05-04T19:36:08.529Z","end":"2026-05-04T19:36:08.629774Z","steps":["trace[1558966589] 'process raft request' (duration: 100.35764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:36:08.629943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.617319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:36:08.630057Z","caller":"traceutil/trace.go:171","msg":"trace[1134372033] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:35069; }","duration":"166.754292ms","start":"2026-05-04T19:36:08.463272Z","end":"2026-05-04T19:36:08.630027Z","steps":["trace[1134372033] 'agreement among raft nodes before linearized reading' (duration: 166.487347ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:36:08.754514Z","caller":"traceutil/trace.go:171","msg":"trace[1609487904] transaction","detail":"{read_only:false; response_revision:35070; number_of_response:1; }","duration":"125.645027ms","start":"2026-05-04T19:36:08.628793Z","end":"2026-05-04T19:36:08.754439Z","steps":["trace[1609487904] 'process raft request' (duration: 103.808365ms)","trace[1609487904] 'compare' (duration: 21.487625ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:36:14.021331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32313} {"level":"info","ts":"2026-05-04T19:36:14.140764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32313,"took":"114.356365ms","hash":552628045,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":40357888,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-04T19:36:14.140856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":552628045,"revision":32313,"compact-revision":29843} {"level":"info","ts":"2026-05-04T19:39:46.338198Z","caller":"traceutil/trace.go:171","msg":"trace[2078742186] transaction","detail":"{read_only:false; response_revision:37224; number_of_response:1; }","duration":"156.949486ms","start":"2026-05-04T19:39:46.181222Z","end":"2026-05-04T19:39:46.338172Z","steps":["trace[2078742186] 'process raft request' (duration: 156.770213ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:46.475339Z","caller":"traceutil/trace.go:171","msg":"trace[329561847] transaction","detail":"{read_only:false; response_revision:37226; number_of_response:1; }","duration":"256.90105ms","start":"2026-05-04T19:39:46.21842Z","end":"2026-05-04T19:39:46.475321Z","steps":["trace[329561847] 'process raft request' (duration: 256.862069ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:46.475484Z","caller":"traceutil/trace.go:171","msg":"trace[1262966392] transaction","detail":"{read_only:false; response_revision:37225; number_of_response:1; }","duration":"293.229834ms","start":"2026-05-04T19:39:46.182233Z","end":"2026-05-04T19:39:46.475463Z","steps":["trace[1262966392] 'process raft request' (duration: 243.70128ms)","trace[1262966392] 'compare' (duration: 49.253718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:46.749214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.892669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:46.749294Z","caller":"traceutil/trace.go:171","msg":"trace[2015880436] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:37226; }","duration":"170.983571ms","start":"2026-05-04T19:39:46.578292Z","end":"2026-05-04T19:39:46.749276Z","steps":["trace[2015880436] 'count revisions from in-memory index tree' (duration: 170.826118ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.344195Z","caller":"traceutil/trace.go:171","msg":"trace[120524599] transaction","detail":"{read_only:false; response_revision:37258; number_of_response:1; }","duration":"109.38298ms","start":"2026-05-04T19:39:51.234787Z","end":"2026-05-04T19:39:51.34417Z","steps":["trace[120524599] 'process raft request' (duration: 109.210107ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.509975Z","caller":"traceutil/trace.go:171","msg":"trace[293444216] transaction","detail":"{read_only:false; response_revision:37260; number_of_response:1; }","duration":"114.021608ms","start":"2026-05-04T19:39:51.395923Z","end":"2026-05-04T19:39:51.509945Z","steps":["trace[293444216] 'process raft request' (duration: 112.407107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:51.751452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.025987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:51.751877Z","caller":"traceutil/trace.go:171","msg":"trace[543375364] transaction","detail":"{read_only:false; response_revision:37261; number_of_response:1; }","duration":"238.119615ms","start":"2026-05-04T19:39:51.513741Z","end":"2026-05-04T19:39:51.751861Z","steps":["trace[543375364] 'process raft request' (duration: 97.609679ms)","trace[543375364] 'compare' (duration: 139.854024ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:51.751985Z","caller":"traceutil/trace.go:171","msg":"trace[1984274514] transaction","detail":"{read_only:false; response_revision:37262; number_of_response:1; }","duration":"237.737018ms","start":"2026-05-04T19:39:51.514242Z","end":"2026-05-04T19:39:51.751979Z","steps":["trace[1984274514] 'process raft request' (duration: 237.294649ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.752063Z","caller":"traceutil/trace.go:171","msg":"trace[2000050972] transaction","detail":"{read_only:false; response_revision:37263; number_of_response:1; }","duration":"217.928354ms","start":"2026-05-04T19:39:51.534128Z","end":"2026-05-04T19:39:51.752057Z","steps":["trace[2000050972] 'process raft request' (duration: 217.442475ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.752154Z","caller":"traceutil/trace.go:171","msg":"trace[1047886615] linearizableReadLoop","detail":"{readStateIndex:38594; appliedIndex:38591; }","duration":"161.743406ms","start":"2026-05-04T19:39:51.590404Z","end":"2026-05-04T19:39:51.752148Z","steps":["trace[1047886615] 'read index received' (duration: 20.962544ms)","trace[1047886615] 'applied index is now lower than readState.Index' (duration: 140.780102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:51.752241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.831138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:51.752259Z","caller":"traceutil/trace.go:171","msg":"trace[1171535950] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:37264; }","duration":"161.891499ms","start":"2026-05-04T19:39:51.59036Z","end":"2026-05-04T19:39:51.752252Z","steps":["trace[1171535950] 'agreement among raft nodes before linearized reading' (duration: 161.848848ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.999218Z","caller":"traceutil/trace.go:171","msg":"trace[697437961] transaction","detail":"{read_only:false; response_revision:37265; number_of_response:1; }","duration":"241.338307ms","start":"2026-05-04T19:39:51.757857Z","end":"2026-05-04T19:39:51.999195Z","steps":["trace[697437961] 'process raft request' (duration: 241.202305ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:51.999726Z","caller":"traceutil/trace.go:171","msg":"trace[888028687] linearizableReadLoop","detail":"{readStateIndex:38596; appliedIndex:38596; }","duration":"212.981422ms","start":"2026-05-04T19:39:51.786733Z","end":"2026-05-04T19:39:51.999714Z","steps":["trace[888028687] 'read index received' (duration: 212.976862ms)","trace[888028687] 'applied index is now lower than readState.Index' (duration: 3.74µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:51.999913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.162785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-04T19:39:51.999955Z","caller":"traceutil/trace.go:171","msg":"trace[1885500084] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:37265; }","duration":"213.218796ms","start":"2026-05-04T19:39:51.786727Z","end":"2026-05-04T19:39:51.999945Z","steps":["trace[1885500084] 'agreement among raft nodes before linearized reading' (duration: 213.073383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:52.007808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.204954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-04T19:39:52.007857Z","caller":"traceutil/trace.go:171","msg":"trace[757452852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37266; }","duration":"146.295556ms","start":"2026-05-04T19:39:51.861551Z","end":"2026-05-04T19:39:52.007846Z","steps":["trace[757452852] 'agreement among raft nodes before linearized reading' (duration: 146.183684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:52.361966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.028658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:52.362108Z","caller":"traceutil/trace.go:171","msg":"trace[394351790] transaction","detail":"{read_only:false; response_revision:37269; number_of_response:1; }","duration":"291.065093ms","start":"2026-05-04T19:39:52.071015Z","end":"2026-05-04T19:39:52.36208Z","steps":["trace[394351790] 'process raft request' (duration: 168.835251ms)","trace[394351790] 'compare' (duration: 121.870956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:52.79309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.063423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:52.793384Z","caller":"traceutil/trace.go:171","msg":"trace[760531661] transaction","detail":"{read_only:false; response_revision:37271; number_of_response:1; }","duration":"435.457612ms","start":"2026-05-04T19:39:52.357889Z","end":"2026-05-04T19:39:52.793347Z","steps":["trace[760531661] 'process raft request' (duration: 435.31704ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:52.793391Z","caller":"traceutil/trace.go:171","msg":"trace[840994623] transaction","detail":"{read_only:false; response_revision:37270; number_of_response:1; }","duration":"456.230214ms","start":"2026-05-04T19:39:52.337117Z","end":"2026-05-04T19:39:52.793347Z","steps":["trace[840994623] 'process raft request' (duration: 293.811005ms)","trace[840994623] 'compare' (duration: 161.91186ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:52.793424Z","caller":"traceutil/trace.go:171","msg":"trace[1757973104] transaction","detail":"{read_only:false; response_revision:37272; number_of_response:1; }","duration":"301.273754ms","start":"2026-05-04T19:39:52.492119Z","end":"2026-05-04T19:39:52.793393Z","steps":["trace[1757973104] 'process raft request' (duration: 301.139402ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:52.79344Z","caller":"traceutil/trace.go:171","msg":"trace[469945264] transaction","detail":"{read_only:false; response_revision:37273; number_of_response:1; }","duration":"300.995739ms","start":"2026-05-04T19:39:52.492404Z","end":"2026-05-04T19:39:52.793399Z","steps":["trace[469945264] 'process raft request' (duration: 300.888827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:52.793546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:52.35786Z","time spent":"435.598915ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:52.793587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:52.492096Z","time spent":"301.402947ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:52.793562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:52.337098Z","time spent":"456.371827ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:52.793598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:52.492374Z","time spent":"301.140682ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:52.947543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.197174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-04T19:39:53.695207Z","caller":"traceutil/trace.go:171","msg":"trace[329903819] transaction","detail":"{read_only:false; response_revision:37275; number_of_response:1; }","duration":"179.854808ms","start":"2026-05-04T19:39:53.515323Z","end":"2026-05-04T19:39:53.695178Z","steps":["trace[329903819] 'process raft request' (duration: 179.650054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:53.930465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.564263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:53.930879Z","caller":"traceutil/trace.go:171","msg":"trace[1704013143] transaction","detail":"{read_only:false; response_revision:37276; number_of_response:1; }","duration":"173.758433ms","start":"2026-05-04T19:39:53.757083Z","end":"2026-05-04T19:39:53.930842Z","steps":["trace[1704013143] 'process raft request' (duration: 28.707951ms)","trace[1704013143] 'compare' (duration: 144.122035ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:53.931142Z","caller":"traceutil/trace.go:171","msg":"trace[1295448920] transaction","detail":"{read_only:false; response_revision:37278; number_of_response:1; }","duration":"169.969951ms","start":"2026-05-04T19:39:53.761146Z","end":"2026-05-04T19:39:53.931116Z","steps":["trace[1295448920] 'process raft request' (duration: 169.668996ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:53.931294Z","caller":"traceutil/trace.go:171","msg":"trace[112440188] transaction","detail":"{read_only:false; response_revision:37277; number_of_response:1; }","duration":"171.262516ms","start":"2026-05-04T19:39:53.760021Z","end":"2026-05-04T19:39:53.931284Z","steps":["trace[112440188] 'process raft request' (duration: 170.622455ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:53.931527Z","caller":"traceutil/trace.go:171","msg":"trace[335456931] transaction","detail":"{read_only:false; response_revision:37279; number_of_response:1; }","duration":"169.875139ms","start":"2026-05-04T19:39:53.761626Z","end":"2026-05-04T19:39:53.931501Z","steps":["trace[335456931] 'process raft request' (duration: 169.306859ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:54.248881Z","caller":"traceutil/trace.go:171","msg":"trace[1355071513] linearizableReadLoop","detail":"{readStateIndex:38613; appliedIndex:38612; }","duration":"148.577119ms","start":"2026-05-04T19:39:54.100278Z","end":"2026-05-04T19:39:54.248855Z","steps":["trace[1355071513] 'read index received' (duration: 148.432336ms)","trace[1355071513] 'applied index is now lower than readState.Index' (duration: 143.713µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:54.248915Z","caller":"traceutil/trace.go:171","msg":"trace[1840891131] transaction","detail":"{read_only:false; response_revision:37281; number_of_response:1; }","duration":"233.59101ms","start":"2026-05-04T19:39:54.015301Z","end":"2026-05-04T19:39:54.248892Z","steps":["trace[1840891131] 'process raft request' (duration: 233.350566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:54.249143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.890946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-04T19:39:54.249128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.084969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-04T19:39:54.249195Z","caller":"traceutil/trace.go:171","msg":"trace[576454750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37281; }","duration":"147.969867ms","start":"2026-05-04T19:39:54.101215Z","end":"2026-05-04T19:39:54.249184Z","steps":["trace[576454750] 'agreement among raft nodes before linearized reading' (duration: 147.905336ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:54.249224Z","caller":"traceutil/trace.go:171","msg":"trace[2068897807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37281; }","duration":"174.222461ms","start":"2026-05-04T19:39:54.07498Z","end":"2026-05-04T19:39:54.249202Z","steps":["trace[2068897807] 'agreement among raft nodes before linearized reading' (duration: 173.929446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:54.249066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.02953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:54.24942Z","caller":"traceutil/trace.go:171","msg":"trace[1037866125] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:37281; }","duration":"224.446178ms","start":"2026-05-04T19:39:54.02496Z","end":"2026-05-04T19:39:54.249406Z","steps":["trace[1037866125] 'agreement among raft nodes before linearized reading' (duration: 224.01501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:54.585014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.895414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-04T19:39:54.585097Z","caller":"traceutil/trace.go:171","msg":"trace[1729324453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:37282; }","duration":"219.042677ms","start":"2026-05-04T19:39:54.366035Z","end":"2026-05-04T19:39:54.585077Z","steps":["trace[1729324453] 'range keys from in-memory index tree' (duration: 218.67589ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:54.926509Z","caller":"traceutil/trace.go:171","msg":"trace[2110743313] transaction","detail":"{read_only:false; response_revision:37284; number_of_response:1; }","duration":"127.37283ms","start":"2026-05-04T19:39:54.799113Z","end":"2026-05-04T19:39:54.926486Z","steps":["trace[2110743313] 'process raft request' (duration: 127.162346ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:55.234379Z","caller":"traceutil/trace.go:171","msg":"trace[924941203] linearizableReadLoop","detail":"{readStateIndex:38618; appliedIndex:38615; }","duration":"431.200001ms","start":"2026-05-04T19:39:54.803157Z","end":"2026-05-04T19:39:55.234358Z","steps":["trace[924941203] 'read index received' (duration: 123.17757ms)","trace[924941203] 'applied index is now lower than readState.Index' (duration: 308.021211ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:55.23444Z","caller":"traceutil/trace.go:171","msg":"trace[151597538] transaction","detail":"{read_only:false; response_revision:37285; number_of_response:1; }","duration":"435.243949ms","start":"2026-05-04T19:39:54.799176Z","end":"2026-05-04T19:39:55.23442Z","steps":["trace[151597538] 'process raft request' (duration: 426.566975ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:55.23451Z","caller":"traceutil/trace.go:171","msg":"trace[1909333377] transaction","detail":"{read_only:false; response_revision:37286; number_of_response:1; }","duration":"435.116867ms","start":"2026-05-04T19:39:54.799382Z","end":"2026-05-04T19:39:55.234499Z","steps":["trace[1909333377] 'process raft request' (duration: 434.864741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:55.234551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:54.799148Z","time spent":"435.339742ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:55.234619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:54.799366Z","time spent":"435.172089ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:39:55.234723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.356894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-04T19:39:55.234848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.694222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:55.234883Z","caller":"traceutil/trace.go:171","msg":"trace[1462697396] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:37286; }","duration":"431.764573ms","start":"2026-05-04T19:39:54.803108Z","end":"2026-05-04T19:39:55.234873Z","steps":["trace[1462697396] 'agreement among raft nodes before linearized reading' (duration: 431.696872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:55.234882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.437351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-04T19:39:55.234911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:54.80309Z","time spent":"431.813314ms","remote":"127.0.0.1:59764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-04T19:39:55.234765Z","caller":"traceutil/trace.go:171","msg":"trace[2121425088] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:37286; }","duration":"312.430056ms","start":"2026-05-04T19:39:54.922321Z","end":"2026-05-04T19:39:55.234752Z","steps":["trace[2121425088] 'agreement among raft nodes before linearized reading' (duration: 312.320924ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:55.234945Z","caller":"traceutil/trace.go:171","msg":"trace[216395579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37286; }","duration":"222.534653ms","start":"2026-05-04T19:39:55.012395Z","end":"2026-05-04T19:39:55.234929Z","steps":["trace[216395579] 'agreement among raft nodes before linearized reading' (duration: 222.449331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:55.234956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:54.922263Z","time spent":"312.68035ms","remote":"127.0.0.1:59350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-05-04T19:39:55.234965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.48983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T19:39:55.235046Z","caller":"traceutil/trace.go:171","msg":"trace[1791981979] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:37286; }","duration":"258.626653ms","start":"2026-05-04T19:39:54.976399Z","end":"2026-05-04T19:39:55.235026Z","steps":["trace[1791981979] 'agreement among raft nodes before linearized reading' (duration: 258.47025ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:55.808731Z","caller":"traceutil/trace.go:171","msg":"trace[791237044] transaction","detail":"{read_only:false; response_revision:37289; number_of_response:1; }","duration":"107.867823ms","start":"2026-05-04T19:39:55.700836Z","end":"2026-05-04T19:39:55.808704Z","steps":["trace[791237044] 'process raft request' (duration: 107.721709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.072811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.761266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T19:39:56.072903Z","caller":"traceutil/trace.go:171","msg":"trace[1287657305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37289; }","duration":"136.919239ms","start":"2026-05-04T19:39:55.93596Z","end":"2026-05-04T19:39:56.072879Z","steps":["trace[1287657305] 'range keys from in-memory index tree' (duration: 136.502542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.072928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.647333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:56.073005Z","caller":"traceutil/trace.go:171","msg":"trace[1267609115] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:37289; }","duration":"240.773846ms","start":"2026-05-04T19:39:55.832212Z","end":"2026-05-04T19:39:56.072986Z","steps":["trace[1267609115] 'count revisions from in-memory index tree' (duration: 240.558672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.073289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.229945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-04T19:39:56.07335Z","caller":"traceutil/trace.go:171","msg":"trace[165056783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37289; }","duration":"137.332307ms","start":"2026-05-04T19:39:55.936003Z","end":"2026-05-04T19:39:56.073335Z","steps":["trace[165056783] 'range keys from in-memory index tree' (duration: 137.100363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.0736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.209242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:56.073694Z","caller":"traceutil/trace.go:171","msg":"trace[1511404761] transaction","detail":"{read_only:false; response_revision:37290; number_of_response:1; }","duration":"137.037052ms","start":"2026-05-04T19:39:55.936638Z","end":"2026-05-04T19:39:56.073675Z","steps":["trace[1511404761] 'process raft request' (duration: 23.682037ms)","trace[1511404761] 'compare' (duration: 112.504519ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:39:56.251923Z","caller":"traceutil/trace.go:171","msg":"trace[1745683756] transaction","detail":"{read_only:false; response_revision:37291; number_of_response:1; }","duration":"314.109957ms","start":"2026-05-04T19:39:55.937784Z","end":"2026-05-04T19:39:56.251894Z","steps":["trace[1745683756] 'process raft request' (duration: 313.880113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.252083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:39:55.937757Z","time spent":"314.238959ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:39:56.25233Z","caller":"traceutil/trace.go:171","msg":"trace[1822019932] linearizableReadLoop","detail":"{readStateIndex:38623; appliedIndex:38622; }","duration":"239.678576ms","start":"2026-05-04T19:39:56.012615Z","end":"2026-05-04T19:39:56.252294Z","steps":["trace[1822019932] 'read index received' (duration: 239.063754ms)","trace[1822019932] 'applied index is now lower than readState.Index' (duration: 611.332µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:56.252462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.854019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:56.252498Z","caller":"traceutil/trace.go:171","msg":"trace[161909812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37291; }","duration":"239.92854ms","start":"2026-05-04T19:39:56.012559Z","end":"2026-05-04T19:39:56.252488Z","steps":["trace[161909812] 'agreement among raft nodes before linearized reading' (duration: 239.866699ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:56.315121Z","caller":"traceutil/trace.go:171","msg":"trace[762316839] transaction","detail":"{read_only:false; response_revision:37292; number_of_response:1; }","duration":"236.831381ms","start":"2026-05-04T19:39:56.07825Z","end":"2026-05-04T19:39:56.315081Z","steps":["trace[762316839] 'process raft request' (duration: 236.530965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.315216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.000024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-04T19:39:56.315147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.807486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T19:39:56.31528Z","caller":"traceutil/trace.go:171","msg":"trace[100600518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37293; }","duration":"211.121076ms","start":"2026-05-04T19:39:56.104141Z","end":"2026-05-04T19:39:56.315262Z","steps":["trace[100600518] 'agreement among raft nodes before linearized reading' (duration: 210.907502ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:56.315313Z","caller":"traceutil/trace.go:171","msg":"trace[1240282645] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:37293; }","duration":"172.085741ms","start":"2026-05-04T19:39:56.143208Z","end":"2026-05-04T19:39:56.315294Z","steps":["trace[1240282645] 'agreement among raft nodes before linearized reading' (duration: 171.810386ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:56.315505Z","caller":"traceutil/trace.go:171","msg":"trace[323842491] transaction","detail":"{read_only:false; response_revision:37293; number_of_response:1; }","duration":"236.728689ms","start":"2026-05-04T19:39:56.078302Z","end":"2026-05-04T19:39:56.31503Z","steps":["trace[323842491] 'process raft request' (duration: 236.650367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:39:56.532541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.301987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:39:56.532726Z","caller":"traceutil/trace.go:171","msg":"trace[2032939059] transaction","detail":"{read_only:false; response_revision:37295; number_of_response:1; }","duration":"212.090465ms","start":"2026-05-04T19:39:56.320619Z","end":"2026-05-04T19:39:56.532709Z","steps":["trace[2032939059] 'process raft request' (duration: 212.027074ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:56.532773Z","caller":"traceutil/trace.go:171","msg":"trace[941514968] linearizableReadLoop","detail":"{readStateIndex:38626; appliedIndex:38625; }","duration":"217.745252ms","start":"2026-05-04T19:39:56.314997Z","end":"2026-05-04T19:39:56.532742Z","steps":["trace[941514968] 'read index received' (duration: 81.158749ms)","trace[941514968] 'applied index is now lower than readState.Index' (duration: 136.584963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:56.532934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.23275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:39:56.533003Z","caller":"traceutil/trace.go:171","msg":"trace[1094254756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37295; }","duration":"279.317291ms","start":"2026-05-04T19:39:56.253645Z","end":"2026-05-04T19:39:56.532962Z","steps":["trace[1094254756] 'agreement among raft nodes before linearized reading' (duration: 279.207609ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:39:56.533123Z","caller":"traceutil/trace.go:171","msg":"trace[1637559686] transaction","detail":"{read_only:false; response_revision:37294; number_of_response:1; }","duration":"278.625698ms","start":"2026-05-04T19:39:56.254483Z","end":"2026-05-04T19:39:56.533109Z","steps":["trace[1637559686] 'process raft request' (duration: 141.659038ms)","trace[1637559686] 'compare' (duration: 135.771718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:39:56.533361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.048722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-04T19:39:56.53341Z","caller":"traceutil/trace.go:171","msg":"trace[766870820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37295; }","duration":"273.128954ms","start":"2026-05-04T19:39:56.260268Z","end":"2026-05-04T19:39:56.533397Z","steps":["trace[766870820] 'agreement among raft nodes before linearized reading' (duration: 272.969171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:03.714198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.940862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:40:03.714685Z","caller":"traceutil/trace.go:171","msg":"trace[192123547] transaction","detail":"{read_only:false; response_revision:37359; number_of_response:1; }","duration":"456.390377ms","start":"2026-05-04T19:40:03.258276Z","end":"2026-05-04T19:40:03.714667Z","steps":["trace[192123547] 'process raft request' (duration: 200.907014ms)","trace[192123547] 'compare' (duration: 254.613526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:03.714931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:03.258248Z","time spent":"456.652422ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:03.719897Z","caller":"traceutil/trace.go:171","msg":"trace[1117938789] transaction","detail":"{read_only:false; response_revision:37360; number_of_response:1; }","duration":"231.858418ms","start":"2026-05-04T19:40:03.488014Z","end":"2026-05-04T19:40:03.719872Z","steps":["trace[1117938789] 'process raft request' (duration: 231.765606ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:04.210069Z","caller":"traceutil/trace.go:171","msg":"trace[1481582279] transaction","detail":"{read_only:false; response_revision:37362; number_of_response:1; }","duration":"106.583898ms","start":"2026-05-04T19:40:04.103423Z","end":"2026-05-04T19:40:04.210006Z","steps":["trace[1481582279] 'process raft request' (duration: 106.438415ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:04.393303Z","caller":"traceutil/trace.go:171","msg":"trace[603190166] transaction","detail":"{read_only:false; response_revision:37363; number_of_response:1; }","duration":"118.753647ms","start":"2026-05-04T19:40:04.274508Z","end":"2026-05-04T19:40:04.393261Z","steps":["trace[603190166] 'process raft request' (duration: 106.391795ms)","trace[603190166] 'compare' (duration: 12.049806ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:04.679771Z","caller":"traceutil/trace.go:171","msg":"trace[326211150] linearizableReadLoop","detail":"{readStateIndex:38698; appliedIndex:38696; }","duration":"330.168219ms","start":"2026-05-04T19:40:04.349588Z","end":"2026-05-04T19:40:04.679757Z","steps":["trace[326211150] 'read index received' (duration: 31.29153ms)","trace[326211150] 'applied index is now lower than readState.Index' (duration: 298.875909ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:04.67983Z","caller":"traceutil/trace.go:171","msg":"trace[483600428] transaction","detail":"{read_only:false; response_revision:37364; number_of_response:1; }","duration":"340.211898ms","start":"2026-05-04T19:40:04.339585Z","end":"2026-05-04T19:40:04.679797Z","steps":["trace[483600428] 'process raft request' (duration: 248.662054ms)","trace[483600428] 'compare' (duration: 91.35028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:04.679944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:04.339557Z","time spent":"340.32546ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:40:04.68003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.409894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-04T19:40:04.680142Z","caller":"traceutil/trace.go:171","msg":"trace[1500465760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37364; }","duration":"330.531267ms","start":"2026-05-04T19:40:04.349581Z","end":"2026-05-04T19:40:04.680112Z","steps":["trace[1500465760] 'agreement among raft nodes before linearized reading' (duration: 330.234751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:04.680211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:04.349511Z","time spent":"330.684079ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T19:40:04.682075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.364451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T19:40:04.68216Z","caller":"traceutil/trace.go:171","msg":"trace[505807451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37365; }","duration":"332.479573ms","start":"2026-05-04T19:40:04.349658Z","end":"2026-05-04T19:40:04.682137Z","steps":["trace[505807451] 'agreement among raft nodes before linearized reading' (duration: 332.261189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:04.682331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:04.349649Z","time spent":"332.664786ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-04T19:40:04.682636Z","caller":"traceutil/trace.go:171","msg":"trace[1350521563] transaction","detail":"{read_only:false; response_revision:37365; number_of_response:1; }","duration":"120.392888ms","start":"2026-05-04T19:40:04.562223Z","end":"2026-05-04T19:40:04.682615Z","steps":["trace[1350521563] 'process raft request' (duration: 119.555322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:04.682874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.457127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:40:04.682925Z","caller":"traceutil/trace.go:171","msg":"trace[1386866784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37365; }","duration":"112.523999ms","start":"2026-05-04T19:40:04.570386Z","end":"2026-05-04T19:40:04.68291Z","steps":["trace[1386866784] 'agreement among raft nodes before linearized reading' (duration: 112.404037ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:05.076906Z","caller":"traceutil/trace.go:171","msg":"trace[1393963604] transaction","detail":"{read_only:false; response_revision:37371; number_of_response:1; }","duration":"121.650691ms","start":"2026-05-04T19:40:04.955227Z","end":"2026-05-04T19:40:05.076878Z","steps":["trace[1393963604] 'process raft request' (duration: 121.484628ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:05.564696Z","caller":"traceutil/trace.go:171","msg":"trace[564430832] linearizableReadLoop","detail":"{readStateIndex:38706; appliedIndex:38705; }","duration":"214.414728ms","start":"2026-05-04T19:40:05.35025Z","end":"2026-05-04T19:40:05.564665Z","steps":["trace[564430832] 'read index received' (duration: 214.144493ms)","trace[564430832] 'applied index is now lower than readState.Index' (duration: 268.995µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:05.564873Z","caller":"traceutil/trace.go:171","msg":"trace[989034288] transaction","detail":"{read_only:false; response_revision:37372; number_of_response:1; }","duration":"263.646887ms","start":"2026-05-04T19:40:05.301186Z","end":"2026-05-04T19:40:05.564833Z","steps":["trace[989034288] 'process raft request' (duration: 263.29041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:05.565009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.731494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-04T19:40:05.565066Z","caller":"traceutil/trace.go:171","msg":"trace[1343234358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:37372; }","duration":"214.806196ms","start":"2026-05-04T19:40:05.350243Z","end":"2026-05-04T19:40:05.56505Z","steps":["trace[1343234358] 'agreement among raft nodes before linearized reading' (duration: 214.550861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:05.858942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.479847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:05.859184Z","caller":"traceutil/trace.go:171","msg":"trace[2075484970] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:37372; }","duration":"139.773443ms","start":"2026-05-04T19:40:05.719392Z","end":"2026-05-04T19:40:05.859165Z","steps":["trace[2075484970] 'range keys from in-memory index tree' (duration: 139.360755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:05.859051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.768175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:05.85954Z","caller":"traceutil/trace.go:171","msg":"trace[87325990] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:37372; }","duration":"224.314646ms","start":"2026-05-04T19:40:05.635213Z","end":"2026-05-04T19:40:05.859528Z","steps":["trace[87325990] 'count revisions from in-memory index tree' (duration: 223.668144ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:05.859801Z","caller":"traceutil/trace.go:171","msg":"trace[229936717] transaction","detail":"{read_only:false; response_revision:37373; number_of_response:1; }","duration":"139.442036ms","start":"2026-05-04T19:40:05.720313Z","end":"2026-05-04T19:40:05.859755Z","steps":["trace[229936717] 'process raft request' (duration: 130.380985ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:05.861963Z","caller":"traceutil/trace.go:171","msg":"trace[114967307] linearizableReadLoop","detail":"{readStateIndex:38708; appliedIndex:38706; }","duration":"123.412565ms","start":"2026-05-04T19:40:05.738527Z","end":"2026-05-04T19:40:05.861939Z","steps":["trace[114967307] 'read index received' (duration: 112.185624ms)","trace[114967307] 'applied index is now lower than readState.Index' (duration: 11.226091ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:05.862167Z","caller":"traceutil/trace.go:171","msg":"trace[575219289] transaction","detail":"{read_only:false; response_revision:37374; number_of_response:1; }","duration":"136.878789ms","start":"2026-05-04T19:40:05.725265Z","end":"2026-05-04T19:40:05.862143Z","steps":["trace[575219289] 'process raft request' (duration: 136.502782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:05.862295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.766401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273013"} {"level":"info","ts":"2026-05-04T19:40:05.862321Z","caller":"traceutil/trace.go:171","msg":"trace[1113768841] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:37374; }","duration":"123.830032ms","start":"2026-05-04T19:40:05.738484Z","end":"2026-05-04T19:40:05.862314Z","steps":["trace[1113768841] 'agreement among raft nodes before linearized reading' (duration: 123.530777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:09.655783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.027179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:09.655866Z","caller":"traceutil/trace.go:171","msg":"trace[1317971616] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:37469; }","duration":"113.159313ms","start":"2026-05-04T19:40:09.542686Z","end":"2026-05-04T19:40:09.655845Z","steps":["trace[1317971616] 'count revisions from in-memory index tree' (duration: 112.941207ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:09.685572Z","caller":"traceutil/trace.go:171","msg":"trace[287951551] transaction","detail":"{read_only:false; response_revision:37470; number_of_response:1; }","duration":"104.162462ms","start":"2026-05-04T19:40:09.581364Z","end":"2026-05-04T19:40:09.685526Z","steps":["trace[287951551] 'process raft request' (duration: 103.890617ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:09.907127Z","caller":"traceutil/trace.go:171","msg":"trace[390080276] transaction","detail":"{read_only:false; response_revision:37471; number_of_response:1; }","duration":"216.310534ms","start":"2026-05-04T19:40:09.690792Z","end":"2026-05-04T19:40:09.907103Z","steps":["trace[390080276] 'process raft request' (duration: 128.844127ms)","trace[390080276] 'compare' (duration: 86.173644ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:10.074168Z","caller":"traceutil/trace.go:171","msg":"trace[895632597] transaction","detail":"{read_only:false; response_revision:37474; number_of_response:1; }","duration":"169.505213ms","start":"2026-05-04T19:40:09.904633Z","end":"2026-05-04T19:40:10.074139Z","steps":["trace[895632597] 'process raft request' (duration: 129.239964ms)","trace[895632597] 'compare' (duration: 40.132267ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:12.077346Z","caller":"traceutil/trace.go:171","msg":"trace[59148364] transaction","detail":"{read_only:false; response_revision:37489; number_of_response:1; }","duration":"165.906395ms","start":"2026-05-04T19:40:11.911416Z","end":"2026-05-04T19:40:12.077323Z","steps":["trace[59148364] 'process raft request' (duration: 122.457725ms)","trace[59148364] 'compare' (duration: 43.227835ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:12.094434Z","caller":"traceutil/trace.go:171","msg":"trace[2103352394] transaction","detail":"{read_only:false; response_revision:37490; number_of_response:1; }","duration":"174.211051ms","start":"2026-05-04T19:40:11.920183Z","end":"2026-05-04T19:40:12.094394Z","steps":["trace[2103352394] 'process raft request' (duration: 174.042338ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.080201Z","caller":"traceutil/trace.go:171","msg":"trace[244816013] transaction","detail":"{read_only:false; response_revision:37551; number_of_response:1; }","duration":"251.187932ms","start":"2026-05-04T19:40:16.828991Z","end":"2026-05-04T19:40:17.080179Z","steps":["trace[244816013] 'process raft request' (duration: 239.195156ms)","trace[244816013] 'compare' (duration: 11.739261ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:17.260534Z","caller":"traceutil/trace.go:171","msg":"trace[1595400258] transaction","detail":"{read_only:false; response_revision:37554; number_of_response:1; }","duration":"143.920081ms","start":"2026-05-04T19:40:17.116586Z","end":"2026-05-04T19:40:17.260506Z","steps":["trace[1595400258] 'process raft request' (duration: 143.87394ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.260752Z","caller":"traceutil/trace.go:171","msg":"trace[308061399] transaction","detail":"{read_only:false; response_revision:37553; number_of_response:1; }","duration":"426.655837ms","start":"2026-05-04T19:40:16.834071Z","end":"2026-05-04T19:40:17.260727Z","steps":["trace[308061399] 'process raft request' (duration: 426.319911ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.260821Z","caller":"traceutil/trace.go:171","msg":"trace[184098212] linearizableReadLoop","detail":"{readStateIndex:38888; appliedIndex:38886; }","duration":"430.391776ms","start":"2026-05-04T19:40:16.830417Z","end":"2026-05-04T19:40:17.260809Z","steps":["trace[184098212] 'read index received' (duration: 237.712586ms)","trace[184098212] 'applied index is now lower than readState.Index' (duration: 192.67811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:17.260902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.834065Z","time spent":"426.754239ms","remote":"127.0.0.1:59754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10634,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:17.260898Z","caller":"traceutil/trace.go:171","msg":"trace[731780232] transaction","detail":"{read_only:false; response_revision:37552; number_of_response:1; }","duration":"431.56709ms","start":"2026-05-04T19:40:16.829302Z","end":"2026-05-04T19:40:17.260869Z","steps":["trace[731780232] 'process raft request' (duration: 384.209377ms)","trace[731780232] 'compare' (duration: 46.718211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:17.261173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.072371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:40:17.261217Z","caller":"traceutil/trace.go:171","msg":"trace[1287967023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37554; }","duration":"430.137562ms","start":"2026-05-04T19:40:16.831064Z","end":"2026-05-04T19:40:17.261202Z","steps":["trace[1287967023] 'agreement among raft nodes before linearized reading' (duration: 430.05465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.831049Z","time spent":"430.196964ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T19:40:17.261249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.00111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-04T19:40:17.261214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.829285Z","time spent":"431.826874ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:17.261298Z","caller":"traceutil/trace.go:171","msg":"trace[812720561] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:37554; }","duration":"257.088492ms","start":"2026-05-04T19:40:17.004195Z","end":"2026-05-04T19:40:17.261283Z","steps":["trace[812720561] 'agreement among raft nodes before linearized reading' (duration: 257.007491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.910248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:17.261371Z","caller":"traceutil/trace.go:171","msg":"trace[1846175458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37554; }","duration":"249.024691ms","start":"2026-05-04T19:40:17.012328Z","end":"2026-05-04T19:40:17.261353Z","steps":["trace[1846175458] 'agreement among raft nodes before linearized reading' (duration: 248.885428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.60273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T19:40:17.261485Z","caller":"traceutil/trace.go:171","msg":"trace[912281061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37554; }","duration":"431.058529ms","start":"2026-05-04T19:40:16.830412Z","end":"2026-05-04T19:40:17.261471Z","steps":["trace[912281061] 'agreement among raft nodes before linearized reading' (duration: 430.518999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.83039Z","time spent":"431.160382ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T19:40:17.261568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.716916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-04T19:40:17.261662Z","caller":"traceutil/trace.go:171","msg":"trace[656992483] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:37554; }","duration":"394.854208ms","start":"2026-05-04T19:40:16.866788Z","end":"2026-05-04T19:40:17.261643Z","steps":["trace[656992483] 'agreement among raft nodes before linearized reading' (duration: 394.569042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.866771Z","time spent":"394.93593ms","remote":"127.0.0.1:59722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-05-04T19:40:17.261727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.075716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-04T19:40:17.261764Z","caller":"traceutil/trace.go:171","msg":"trace[2000023559] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:37554; }","duration":"384.141167ms","start":"2026-05-04T19:40:16.877612Z","end":"2026-05-04T19:40:17.261754Z","steps":["trace[2000023559] 'agreement among raft nodes before linearized reading' (duration: 383.992244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.261807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:16.877597Z","time spent":"384.199208ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":143,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-04T19:40:17.474139Z","caller":"traceutil/trace.go:171","msg":"trace[1650916017] linearizableReadLoop","detail":"{readStateIndex:38891; appliedIndex:38890; }","duration":"207.945906ms","start":"2026-05-04T19:40:17.266165Z","end":"2026-05-04T19:40:17.474111Z","steps":["trace[1650916017] 'read index received' (duration: 193.481634ms)","trace[1650916017] 'applied index is now lower than readState.Index' (duration: 14.457042ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:17.474373Z","caller":"traceutil/trace.go:171","msg":"trace[1824107509] transaction","detail":"{read_only:false; response_revision:37555; number_of_response:1; }","duration":"208.683501ms","start":"2026-05-04T19:40:17.265662Z","end":"2026-05-04T19:40:17.474345Z","steps":["trace[1824107509] 'process raft request' (duration: 193.965674ms)","trace[1824107509] 'compare' (duration: 14.080715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:17.474528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.336475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b7ddeca1-16dc-4bf7-a9ac-63889d09c955\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-04T19:40:17.474606Z","caller":"traceutil/trace.go:171","msg":"trace[1556164121] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b7ddeca1-16dc-4bf7-a9ac-63889d09c955; range_end:; response_count:1; response_revision:37555; }","duration":"208.446947ms","start":"2026-05-04T19:40:17.266139Z","end":"2026-05-04T19:40:17.474586Z","steps":["trace[1556164121] 'agreement among raft nodes before linearized reading' (duration: 208.202712ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.601942Z","caller":"traceutil/trace.go:171","msg":"trace[1612738600] transaction","detail":"{read_only:false; response_revision:37556; number_of_response:1; }","duration":"335.311607ms","start":"2026-05-04T19:40:17.266579Z","end":"2026-05-04T19:40:17.601891Z","steps":["trace[1612738600] 'process raft request' (duration: 335.038412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.602151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:17.266567Z","time spent":"335.47506ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:17.602332Z","caller":"traceutil/trace.go:171","msg":"trace[408384236] transaction","detail":"{read_only:false; response_revision:37557; number_of_response:1; }","duration":"335.625082ms","start":"2026-05-04T19:40:17.266682Z","end":"2026-05-04T19:40:17.602307Z","steps":["trace[408384236] 'process raft request' (duration: 335.103243ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.602438Z","caller":"traceutil/trace.go:171","msg":"trace[1308735317] transaction","detail":"{read_only:false; response_revision:37559; number_of_response:1; }","duration":"334.657743ms","start":"2026-05-04T19:40:17.267752Z","end":"2026-05-04T19:40:17.60241Z","steps":["trace[1308735317] 'process raft request' (duration: 334.193875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.602508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:17.266675Z","time spent":"335.753935ms","remote":"127.0.0.1:59734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:40:17.602551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:17.267743Z","time spent":"334.758295ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:17.60274Z","caller":"traceutil/trace.go:171","msg":"trace[1278565270] transaction","detail":"{read_only:false; response_revision:37558; number_of_response:1; }","duration":"335.842146ms","start":"2026-05-04T19:40:17.266883Z","end":"2026-05-04T19:40:17.602725Z","steps":["trace[1278565270] 'process raft request' (duration: 335.009511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.602839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:17.266875Z","time spent":"335.910137ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:17.603039Z","caller":"traceutil/trace.go:171","msg":"trace[137977824] transaction","detail":"{read_only:false; response_revision:37560; number_of_response:1; }","duration":"334.896828ms","start":"2026-05-04T19:40:17.268129Z","end":"2026-05-04T19:40:17.603026Z","steps":["trace[137977824] 'process raft request' (duration: 333.851318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:17.603111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:17.2681Z","time spent":"334.97724ms","remote":"127.0.0.1:59734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:40:17.603644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.490033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-04T19:40:17.603732Z","caller":"traceutil/trace.go:171","msg":"trace[383316664] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:37560; }","duration":"246.638156ms","start":"2026-05-04T19:40:17.357073Z","end":"2026-05-04T19:40:17.603711Z","steps":["trace[383316664] 'agreement among raft nodes before linearized reading' (duration: 246.359441ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:17.602051Z","caller":"traceutil/trace.go:171","msg":"trace[1093243627] linearizableReadLoop","detail":"{readStateIndex:38896; appliedIndex:38891; }","duration":"127.812928ms","start":"2026-05-04T19:40:17.474213Z","end":"2026-05-04T19:40:17.602026Z","steps":["trace[1093243627] 'read index received' (duration: 127.322049ms)","trace[1093243627] 'applied index is now lower than readState.Index' (duration: 489.689µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:26.570493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.985929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:40:26.570654Z","caller":"traceutil/trace.go:171","msg":"trace[471343825] transaction","detail":"{read_only:false; response_revision:37634; number_of_response:1; }","duration":"199.865226ms","start":"2026-05-04T19:40:26.370765Z","end":"2026-05-04T19:40:26.57063Z","steps":["trace[471343825] 'process raft request' (duration: 61.620521ms)","trace[471343825] 'compare' (duration: 137.783256ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:26.586189Z","caller":"traceutil/trace.go:171","msg":"trace[2090347703] transaction","detail":"{read_only:false; response_revision:37635; number_of_response:1; }","duration":"116.638547ms","start":"2026-05-04T19:40:26.469528Z","end":"2026-05-04T19:40:26.586166Z","steps":["trace[2090347703] 'process raft request' (duration: 116.503015ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:26.861604Z","caller":"traceutil/trace.go:171","msg":"trace[996638226] transaction","detail":"{read_only:false; response_revision:37636; number_of_response:1; }","duration":"111.032031ms","start":"2026-05-04T19:40:26.750512Z","end":"2026-05-04T19:40:26.861544Z","steps":["trace[996638226] 'process raft request' (duration: 110.685175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:27.147573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.700023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:40:27.147647Z","caller":"traceutil/trace.go:171","msg":"trace[1555143142] linearizableReadLoop","detail":"{readStateIndex:38975; appliedIndex:38974; }","duration":"171.618403ms","start":"2026-05-04T19:40:26.976018Z","end":"2026-05-04T19:40:27.147637Z","steps":["trace[1555143142] 'read index received' (duration: 24.760006ms)","trace[1555143142] 'applied index is now lower than readState.Index' (duration: 146.857187ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:27.147774Z","caller":"traceutil/trace.go:171","msg":"trace[522577128] transaction","detail":"{read_only:false; response_revision:37637; number_of_response:1; }","duration":"282.633114ms","start":"2026-05-04T19:40:26.865134Z","end":"2026-05-04T19:40:27.147767Z","steps":["trace[522577128] 'process raft request' (duration: 135.681166ms)","trace[522577128] 'compare' (duration: 146.443348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:27.147853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.049615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:27.147891Z","caller":"traceutil/trace.go:171","msg":"trace[1661454509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:37637; }","duration":"134.129137ms","start":"2026-05-04T19:40:27.01375Z","end":"2026-05-04T19:40:27.147879Z","steps":["trace[1661454509] 'agreement among raft nodes before linearized reading' (duration: 134.071476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:27.147939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.9308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:27.147958Z","caller":"traceutil/trace.go:171","msg":"trace[737014593] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:37637; }","duration":"171.981ms","start":"2026-05-04T19:40:26.975969Z","end":"2026-05-04T19:40:27.14795Z","steps":["trace[737014593] 'agreement among raft nodes before linearized reading' (duration: 171.948449ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:27.345591Z","caller":"traceutil/trace.go:171","msg":"trace[1263805302] linearizableReadLoop","detail":"{readStateIndex:38977; appliedIndex:38975; }","duration":"114.188891ms","start":"2026-05-04T19:40:27.231382Z","end":"2026-05-04T19:40:27.345571Z","steps":["trace[1263805302] 'read index received' (duration: 49.281809ms)","trace[1263805302] 'applied index is now lower than readState.Index' (duration: 64.906472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:27.345713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.319923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:27.345699Z","caller":"traceutil/trace.go:171","msg":"trace[775708974] transaction","detail":"{read_only:false; response_revision:37639; number_of_response:1; }","duration":"148.033128ms","start":"2026-05-04T19:40:27.197624Z","end":"2026-05-04T19:40:27.345657Z","steps":["trace[775708974] 'process raft request' (duration: 147.914586ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:27.345711Z","caller":"traceutil/trace.go:171","msg":"trace[1364814290] transaction","detail":"{read_only:false; response_revision:37638; number_of_response:1; }","duration":"193.501174ms","start":"2026-05-04T19:40:27.152157Z","end":"2026-05-04T19:40:27.345658Z","steps":["trace[1364814290] 'process raft request' (duration: 128.4806ms)","trace[1364814290] 'compare' (duration: 64.78935ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:27.345741Z","caller":"traceutil/trace.go:171","msg":"trace[1792495628] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:37639; }","duration":"114.363504ms","start":"2026-05-04T19:40:27.231367Z","end":"2026-05-04T19:40:27.34573Z","steps":["trace[1792495628] 'agreement among raft nodes before linearized reading' (duration: 114.295663ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:27.428121Z","caller":"traceutil/trace.go:171","msg":"trace[862949977] transaction","detail":"{read_only:false; response_revision:37640; number_of_response:1; }","duration":"130.314176ms","start":"2026-05-04T19:40:27.297761Z","end":"2026-05-04T19:40:27.428075Z","steps":["trace[862949977] 'process raft request' (duration: 130.104262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:27.428282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.226194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-04T19:40:27.428379Z","caller":"traceutil/trace.go:171","msg":"trace[548637921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37640; }","duration":"112.398167ms","start":"2026-05-04T19:40:27.315958Z","end":"2026-05-04T19:40:27.428356Z","steps":["trace[548637921] 'agreement among raft nodes before linearized reading' (duration: 112.128932ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:27.57031Z","caller":"traceutil/trace.go:171","msg":"trace[1773474380] transaction","detail":"{read_only:false; response_revision:37641; number_of_response:1; }","duration":"136.932819ms","start":"2026-05-04T19:40:27.433354Z","end":"2026-05-04T19:40:27.570287Z","steps":["trace[1773474380] 'process raft request' (duration: 136.622753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:27.873281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.771657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:40:27.873442Z","caller":"traceutil/trace.go:171","msg":"trace[965217635] linearizableReadLoop","detail":"{readStateIndex:38982; appliedIndex:38980; }","duration":"228.577636ms","start":"2026-05-04T19:40:27.644848Z","end":"2026-05-04T19:40:27.873426Z","steps":["trace[965217635] 'read index received' (duration: 56.762678ms)","trace[965217635] 'applied index is now lower than readState.Index' (duration: 171.814098ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:27.873504Z","caller":"traceutil/trace.go:171","msg":"trace[288527961] transaction","detail":"{read_only:false; response_revision:37642; number_of_response:1; }","duration":"302.374646ms","start":"2026-05-04T19:40:27.571119Z","end":"2026-05-04T19:40:27.873493Z","steps":["trace[288527961] 'process raft request' (duration: 130.302934ms)","trace[288527961] 'compare' (duration: 171.632463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:27.873554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:27.571104Z","time spent":"302.426707ms","remote":"127.0.0.1:59598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:40:27.873658Z","caller":"traceutil/trace.go:171","msg":"trace[1493429575] transaction","detail":"{read_only:false; response_revision:37643; number_of_response:1; }","duration":"299.296528ms","start":"2026-05-04T19:40:27.574335Z","end":"2026-05-04T19:40:27.873631Z","steps":["trace[1493429575] 'process raft request' (duration: 299.042443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:27.873943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.075806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:40:27.874026Z","caller":"traceutil/trace.go:171","msg":"trace[1831298115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37643; }","duration":"229.213909ms","start":"2026-05-04T19:40:27.644791Z","end":"2026-05-04T19:40:27.874005Z","steps":["trace[1831298115] 'agreement among raft nodes before linearized reading' (duration: 228.945474ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:27.918547Z","caller":"traceutil/trace.go:171","msg":"trace[307036056] transaction","detail":"{read_only:false; response_revision:37644; number_of_response:1; }","duration":"150.980384ms","start":"2026-05-04T19:40:27.767533Z","end":"2026-05-04T19:40:27.918513Z","steps":["trace[307036056] 'process raft request' (duration: 150.74359ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:28.0053Z","caller":"traceutil/trace.go:171","msg":"trace[1164297744] linearizableReadLoop","detail":"{readStateIndex:38984; appliedIndex:38982; }","duration":"131.814682ms","start":"2026-05-04T19:40:27.873463Z","end":"2026-05-04T19:40:28.005277Z","steps":["trace[1164297744] 'read index received' (duration: 44.796673ms)","trace[1164297744] 'applied index is now lower than readState.Index' (duration: 87.016629ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:28.005435Z","caller":"traceutil/trace.go:171","msg":"trace[1964220518] transaction","detail":"{read_only:false; response_revision:37646; number_of_response:1; }","duration":"127.40592ms","start":"2026-05-04T19:40:27.878014Z","end":"2026-05-04T19:40:28.00542Z","steps":["trace[1964220518] 'process raft request' (duration: 127.224706ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:28.005408Z","caller":"traceutil/trace.go:171","msg":"trace[1545784994] transaction","detail":"{read_only:false; response_revision:37645; number_of_response:1; }","duration":"165.562268ms","start":"2026-05-04T19:40:27.839783Z","end":"2026-05-04T19:40:28.005345Z","steps":["trace[1545784994] 'process raft request' (duration: 165.334904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:28.005596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.846307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-04T19:40:28.005678Z","caller":"traceutil/trace.go:171","msg":"trace[1563005840] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:37646; }","duration":"111.953408ms","start":"2026-05-04T19:40:27.8937Z","end":"2026-05-04T19:40:28.005653Z","steps":["trace[1563005840] 'agreement among raft nodes before linearized reading' (duration: 111.771405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:28.006067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.482541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-04T19:40:28.006151Z","caller":"traceutil/trace.go:171","msg":"trace[119962074] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:37646; }","duration":"359.622964ms","start":"2026-05-04T19:40:27.646506Z","end":"2026-05-04T19:40:28.006129Z","steps":["trace[119962074] 'agreement among raft nodes before linearized reading' (duration: 359.192836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:28.006206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:40:27.646496Z","time spent":"359.694805ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":181,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-04T19:40:30.067949Z","caller":"traceutil/trace.go:171","msg":"trace[509794203] linearizableReadLoop","detail":"{readStateIndex:38997; appliedIndex:38996; }","duration":"115.901103ms","start":"2026-05-04T19:40:29.951972Z","end":"2026-05-04T19:40:30.067873Z","steps":["trace[509794203] 'read index received' (duration: 66.602954ms)","trace[509794203] 'applied index is now lower than readState.Index' (duration: 49.296649ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:30.067948Z","caller":"traceutil/trace.go:171","msg":"trace[1299646153] transaction","detail":"{read_only:false; response_revision:37658; number_of_response:1; }","duration":"144.035593ms","start":"2026-05-04T19:40:29.923869Z","end":"2026-05-04T19:40:30.067905Z","steps":["trace[1299646153] 'process raft request' (duration: 94.770074ms)","trace[1299646153] 'compare' (duration: 48.374122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:40:30.068856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.273374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:30.068935Z","caller":"traceutil/trace.go:171","msg":"trace[1958421275] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:37658; }","duration":"121.426517ms","start":"2026-05-04T19:40:29.947485Z","end":"2026-05-04T19:40:30.068912Z","steps":["trace[1958421275] 'agreement among raft nodes before linearized reading' (duration: 120.584162ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:30.201727Z","caller":"traceutil/trace.go:171","msg":"trace[1821890968] transaction","detail":"{read_only:false; response_revision:37659; number_of_response:1; }","duration":"128.527271ms","start":"2026-05-04T19:40:30.073173Z","end":"2026-05-04T19:40:30.2017Z","steps":["trace[1821890968] 'process raft request' (duration: 101.318229ms)","trace[1821890968] 'compare' (duration: 27.107731ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:30.244458Z","caller":"traceutil/trace.go:171","msg":"trace[1945343751] transaction","detail":"{read_only:false; response_revision:37660; number_of_response:1; }","duration":"107.882513ms","start":"2026-05-04T19:40:30.136556Z","end":"2026-05-04T19:40:30.244439Z","steps":["trace[1945343751] 'process raft request' (duration: 107.756591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:34.015351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.630729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T19:40:34.015478Z","caller":"traceutil/trace.go:171","msg":"trace[1944435613] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:37684; }","duration":"191.797393ms","start":"2026-05-04T19:40:33.823666Z","end":"2026-05-04T19:40:34.015463Z","steps":["trace[1944435613] 'count revisions from in-memory index tree' (duration: 191.504787ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:35.848787Z","caller":"traceutil/trace.go:171","msg":"trace[1595948898] linearizableReadLoop","detail":"{readStateIndex:39048; appliedIndex:39047; }","duration":"100.045755ms","start":"2026-05-04T19:40:35.748714Z","end":"2026-05-04T19:40:35.84876Z","steps":["trace[1595948898] 'read index received' (duration: 99.899642ms)","trace[1595948898] 'applied index is now lower than readState.Index' (duration: 145.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:35.848832Z","caller":"traceutil/trace.go:171","msg":"trace[1264553560] transaction","detail":"{read_only:false; response_revision:37707; number_of_response:1; }","duration":"103.088962ms","start":"2026-05-04T19:40:35.745715Z","end":"2026-05-04T19:40:35.848804Z","steps":["trace[1264553560] 'process raft request' (duration: 102.869768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:35.848989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.263139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-04T19:40:35.849028Z","caller":"traceutil/trace.go:171","msg":"trace[1038357130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37707; }","duration":"100.354541ms","start":"2026-05-04T19:40:35.748664Z","end":"2026-05-04T19:40:35.849018Z","steps":["trace[1038357130] 'agreement among raft nodes before linearized reading' (duration: 100.199238ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:36.042401Z","caller":"traceutil/trace.go:171","msg":"trace[1048887545] transaction","detail":"{read_only:false; response_revision:37708; number_of_response:1; }","duration":"189.285996ms","start":"2026-05-04T19:40:35.853079Z","end":"2026-05-04T19:40:36.042365Z","steps":["trace[1048887545] 'process raft request' (duration: 141.589357ms)","trace[1048887545] 'compare' (duration: 47.553746ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:36.042941Z","caller":"traceutil/trace.go:171","msg":"trace[255643959] linearizableReadLoop","detail":"{readStateIndex:39050; appliedIndex:39048; }","duration":"132.342742ms","start":"2026-05-04T19:40:35.910575Z","end":"2026-05-04T19:40:36.042918Z","steps":["trace[255643959] 'read index received' (duration: 84.126724ms)","trace[255643959] 'applied index is now lower than readState.Index' (duration: 48.214868ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:36.043411Z","caller":"traceutil/trace.go:171","msg":"trace[1958003661] transaction","detail":"{read_only:false; response_revision:37709; number_of_response:1; }","duration":"185.720519ms","start":"2026-05-04T19:40:35.857655Z","end":"2026-05-04T19:40:36.043376Z","steps":["trace[1958003661] 'process raft request' (duration: 185.014106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:36.0434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.776521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T19:40:36.043521Z","caller":"traceutil/trace.go:171","msg":"trace[1480463736] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:37709; }","duration":"132.958154ms","start":"2026-05-04T19:40:35.910534Z","end":"2026-05-04T19:40:36.043492Z","steps":["trace[1480463736] 'agreement among raft nodes before linearized reading' (duration: 132.72248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:40.635994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.325848ms","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:8"} {"level":"info","ts":"2026-05-04T19:40:40.636094Z","caller":"traceutil/trace.go:171","msg":"trace[1688602363] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:37751; }","duration":"110.491071ms","start":"2026-05-04T19:40:40.525581Z","end":"2026-05-04T19:40:40.636072Z","steps":["trace[1688602363] 'count revisions from in-memory index tree' (duration: 110.239336ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:42.253805Z","caller":"traceutil/trace.go:171","msg":"trace[1273173500] linearizableReadLoop","detail":"{readStateIndex:39099; appliedIndex:39098; }","duration":"164.55643ms","start":"2026-05-04T19:40:42.089226Z","end":"2026-05-04T19:40:42.253783Z","steps":["trace[1273173500] 'read index received' (duration: 164.480739ms)","trace[1273173500] 'applied index is now lower than readState.Index' (duration: 74.831µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:42.253855Z","caller":"traceutil/trace.go:171","msg":"trace[232679236] transaction","detail":"{read_only:false; response_revision:37757; number_of_response:1; }","duration":"186.951872ms","start":"2026-05-04T19:40:42.066861Z","end":"2026-05-04T19:40:42.253813Z","steps":["trace[232679236] 'process raft request' (duration: 186.792149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:42.25408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.828495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-04T19:40:42.254163Z","caller":"traceutil/trace.go:171","msg":"trace[2792815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37757; }","duration":"164.963308ms","start":"2026-05-04T19:40:42.089182Z","end":"2026-05-04T19:40:42.254145Z","steps":["trace[2792815] 'agreement among raft nodes before linearized reading' (duration: 164.699663ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:42.28011Z","caller":"traceutil/trace.go:171","msg":"trace[667344359] transaction","detail":"{read_only:false; response_revision:37758; number_of_response:1; }","duration":"172.907306ms","start":"2026-05-04T19:40:42.107186Z","end":"2026-05-04T19:40:42.280094Z","steps":["trace[667344359] 'process raft request' (duration: 172.728043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:53.842284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.391854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-04T19:40:53.842377Z","caller":"traceutil/trace.go:171","msg":"trace[1139477068] transaction","detail":"{read_only:false; response_revision:37847; number_of_response:1; }","duration":"262.386983ms","start":"2026-05-04T19:40:53.579974Z","end":"2026-05-04T19:40:53.842361Z","steps":["trace[1139477068] 'process raft request' (duration: 120.848537ms)","trace[1139477068] 'compare' (duration: 140.960216ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:54.481537Z","caller":"traceutil/trace.go:171","msg":"trace[713336641] transaction","detail":"{read_only:false; response_revision:37849; number_of_response:1; }","duration":"151.624576ms","start":"2026-05-04T19:40:54.329888Z","end":"2026-05-04T19:40:54.481513Z","steps":["trace[713336641] 'process raft request' (duration: 151.117997ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:54.481859Z","caller":"traceutil/trace.go:171","msg":"trace[2002144402] linearizableReadLoop","detail":"{readStateIndex:39197; appliedIndex:39193; }","duration":"123.18095ms","start":"2026-05-04T19:40:54.358619Z","end":"2026-05-04T19:40:54.4818Z","steps":["trace[2002144402] 'read index received' (duration: 37.144719ms)","trace[2002144402] 'applied index is now lower than readState.Index' (duration: 86.035031ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:40:54.482067Z","caller":"traceutil/trace.go:171","msg":"trace[878373392] transaction","detail":"{read_only:false; response_revision:37852; number_of_response:1; }","duration":"131.530678ms","start":"2026-05-04T19:40:54.350518Z","end":"2026-05-04T19:40:54.482049Z","steps":["trace[878373392] 'process raft request' (duration: 131.233552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:54.48227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.637409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:40:54.482324Z","caller":"traceutil/trace.go:171","msg":"trace[957393206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37852; }","duration":"123.730011ms","start":"2026-05-04T19:40:54.358579Z","end":"2026-05-04T19:40:54.482309Z","steps":["trace[957393206] 'agreement among raft nodes before linearized reading' (duration: 123.558717ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:54.482352Z","caller":"traceutil/trace.go:171","msg":"trace[93566348] transaction","detail":"{read_only:false; response_revision:37851; number_of_response:1; }","duration":"134.067316ms","start":"2026-05-04T19:40:54.348269Z","end":"2026-05-04T19:40:54.482336Z","steps":["trace[93566348] 'process raft request' (duration: 133.365033ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:54.482346Z","caller":"traceutil/trace.go:171","msg":"trace[1338683002] transaction","detail":"{read_only:false; response_revision:37850; number_of_response:1; }","duration":"135.724636ms","start":"2026-05-04T19:40:54.346574Z","end":"2026-05-04T19:40:54.482299Z","steps":["trace[1338683002] 'process raft request' (duration: 134.888831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:40:54.482686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T19:40:54.482732Z","caller":"traceutil/trace.go:171","msg":"trace[1067823283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37852; }","duration":"120.016491ms","start":"2026-05-04T19:40:54.362704Z","end":"2026-05-04T19:40:54.482721Z","steps":["trace[1067823283] 'agreement among raft nodes before linearized reading' (duration: 119.769497ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:59.69972Z","caller":"traceutil/trace.go:171","msg":"trace[1412225200] transaction","detail":"{read_only:false; response_revision:37900; number_of_response:1; }","duration":"106.639289ms","start":"2026-05-04T19:40:59.593041Z","end":"2026-05-04T19:40:59.69968Z","steps":["trace[1412225200] 'process raft request' (duration: 45.994306ms)","trace[1412225200] 'compare' (duration: 60.319176ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:41:00.707113Z","caller":"traceutil/trace.go:171","msg":"trace[1578777856] linearizableReadLoop","detail":"{readStateIndex:39252; appliedIndex:39247; }","duration":"199.502858ms","start":"2026-05-04T19:41:00.507592Z","end":"2026-05-04T19:41:00.707095Z","steps":["trace[1578777856] 'read index received' (duration: 4.899013ms)","trace[1578777856] 'applied index is now lower than readState.Index' (duration: 194.603165ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:41:00.707207Z","caller":"traceutil/trace.go:171","msg":"trace[1189971390] transaction","detail":"{read_only:false; response_revision:37905; number_of_response:1; }","duration":"204.302849ms","start":"2026-05-04T19:41:00.502895Z","end":"2026-05-04T19:41:00.707198Z","steps":["trace[1189971390] 'process raft request' (duration: 204.142126ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:00.70717Z","caller":"traceutil/trace.go:171","msg":"trace[1579066096] transaction","detail":"{read_only:false; response_revision:37904; number_of_response:1; }","duration":"206.029611ms","start":"2026-05-04T19:41:00.501094Z","end":"2026-05-04T19:41:00.707124Z","steps":["trace[1579066096] 'process raft request' (duration: 205.903779ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:00.707393Z","caller":"traceutil/trace.go:171","msg":"trace[1822350480] transaction","detail":"{read_only:false; response_revision:37906; number_of_response:1; }","duration":"204.468032ms","start":"2026-05-04T19:41:00.502917Z","end":"2026-05-04T19:41:00.707385Z","steps":["trace[1822350480] 'process raft request' (duration: 204.145626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:41:00.707704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.084838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T19:41:00.707734Z","caller":"traceutil/trace.go:171","msg":"trace[542864016] transaction","detail":"{read_only:false; response_revision:37903; number_of_response:1; }","duration":"207.746394ms","start":"2026-05-04T19:41:00.499717Z","end":"2026-05-04T19:41:00.707464Z","steps":["trace[542864016] 'process raft request' (duration: 190.292365ms)","trace[542864016] 'compare' (duration: 16.867078ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:41:00.707815Z","caller":"traceutil/trace.go:171","msg":"trace[1039680085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37906; }","duration":"200.239552ms","start":"2026-05-04T19:41:00.507555Z","end":"2026-05-04T19:41:00.707795Z","steps":["trace[1039680085] 'agreement among raft nodes before linearized reading' (duration: 199.918206ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:00.709091Z","caller":"traceutil/trace.go:171","msg":"trace[453178485] transaction","detail":"{read_only:false; response_revision:37907; number_of_response:1; }","duration":"160.062955ms","start":"2026-05-04T19:41:00.548988Z","end":"2026-05-04T19:41:00.709051Z","steps":["trace[453178485] 'process raft request' (duration: 159.81191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:41:00.709123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.100319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-04T19:41:00.709435Z","caller":"traceutil/trace.go:171","msg":"trace[1371935910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:37907; }","duration":"201.419594ms","start":"2026-05-04T19:41:00.507987Z","end":"2026-05-04T19:41:00.709406Z","steps":["trace[1371935910] 'agreement among raft nodes before linearized reading' (duration: 200.971096ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:00.863191Z","caller":"traceutil/trace.go:171","msg":"trace[1699278899] linearizableReadLoop","detail":"{readStateIndex:39255; appliedIndex:39254; }","duration":"121.56869ms","start":"2026-05-04T19:41:00.74159Z","end":"2026-05-04T19:41:00.863159Z","steps":["trace[1699278899] 'read index received' (duration: 95.973028ms)","trace[1699278899] 'applied index is now lower than readState.Index' (duration: 25.588092ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:41:00.863216Z","caller":"traceutil/trace.go:171","msg":"trace[2060825425] transaction","detail":"{read_only:false; response_revision:37909; number_of_response:1; }","duration":"149.218971ms","start":"2026-05-04T19:41:00.713947Z","end":"2026-05-04T19:41:00.863166Z","steps":["trace[2060825425] 'process raft request' (duration: 123.103029ms)","trace[2060825425] 'compare' (duration: 25.843677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:41:00.86339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.759623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T19:41:00.863528Z","caller":"traceutil/trace.go:171","msg":"trace[1657208112] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:37910; }","duration":"121.899985ms","start":"2026-05-04T19:41:00.741565Z","end":"2026-05-04T19:41:00.863465Z","steps":["trace[1657208112] 'agreement among raft nodes before linearized reading' (duration: 121.712802ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:14.028645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35124} {"level":"info","ts":"2026-05-04T19:41:14.147182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35124,"took":"114.593728ms","hash":3578863916,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":34791424,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-04T19:41:14.147235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3578863916,"revision":35124,"compact-revision":32313} {"level":"info","ts":"2026-05-04T19:41:36.971112Z","caller":"traceutil/trace.go:171","msg":"trace[665386138] transaction","detail":"{read_only:false; response_revision:38345; number_of_response:1; }","duration":"129.197954ms","start":"2026-05-04T19:41:36.841897Z","end":"2026-05-04T19:41:36.971095Z","steps":["trace[665386138] 'process raft request' (duration: 129.077862ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:36.978642Z","caller":"traceutil/trace.go:171","msg":"trace[325300431] transaction","detail":"{read_only:false; response_revision:38347; number_of_response:1; }","duration":"122.175062ms","start":"2026-05-04T19:41:36.856451Z","end":"2026-05-04T19:41:36.978626Z","steps":["trace[325300431] 'process raft request' (duration: 121.862496ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:36.978589Z","caller":"traceutil/trace.go:171","msg":"trace[996666276] transaction","detail":"{read_only:false; response_revision:38346; number_of_response:1; }","duration":"123.411725ms","start":"2026-05-04T19:41:36.855137Z","end":"2026-05-04T19:41:36.978549Z","steps":["trace[996666276] 'process raft request' (duration: 123.107839ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:41:36.978839Z","caller":"traceutil/trace.go:171","msg":"trace[1975850415] transaction","detail":"{read_only:false; response_revision:38348; number_of_response:1; }","duration":"120.158864ms","start":"2026-05-04T19:41:36.858663Z","end":"2026-05-04T19:41:36.978822Z","steps":["trace[1975850415] 'process raft request' (duration: 119.684105ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:42:08.973275Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T19:42:08.978682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-04T19:42:08.978859Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-04T19:46:14.036988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38005} {"level":"info","ts":"2026-05-04T19:46:14.158378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38005,"took":"118.279719ms","hash":4207065270,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":34836480,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-04T19:46:14.158444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4207065270,"revision":38005,"compact-revision":35124} {"level":"info","ts":"2026-05-04T19:51:14.044232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40913} {"level":"info","ts":"2026-05-04T19:51:14.161849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40913,"took":"114.50637ms","hash":708428710,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":29597696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-04T19:51:14.161905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":708428710,"revision":40913,"compact-revision":38005} {"level":"info","ts":"2026-05-04T19:56:14.053807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43137} {"level":"info","ts":"2026-05-04T19:56:14.158331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43137,"took":"100.709341ms","hash":2877640761,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":28372992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-04T19:56:14.158403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2877640761,"revision":43137,"compact-revision":40913} {"level":"info","ts":"2026-05-04T20:01:14.059399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":45363} {"level":"info","ts":"2026-05-04T20:01:14.163514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":45363,"took":"101.191ms","hash":3753146808,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":30924800,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-04T20:01:14.163586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3753146808,"revision":45363,"compact-revision":43137}