{"level":"warn","ts":"2026-04-22T16:28:53.17495Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:28:53.175099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.179: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.179:2380","--initial-cluster=instance=https://199.19.213.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.179:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T16:28:53.17521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:28:53.175234Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-04-22T16:28:53.175266Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:28:53.17629Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"]} {"level":"info","ts":"2026-04-22T16:28:53.176413Z","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.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.179:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T16:28:53.179944Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.116102ms"} {"level":"info","ts":"2026-04-22T16:28:53.185351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"69895f7c3b831156","cluster-id":"caac76e5ff14ceff"} {"level":"info","ts":"2026-04-22T16:28:53.185497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:28:53.185539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 0"} {"level":"info","ts":"2026-04-22T16:28:53.185575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 69895f7c3b831156 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:28:53.185588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 1"} {"level":"info","ts":"2026-04-22T16:28:53.185649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"warn","ts":"2026-04-22T16:28:53.191406Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:28:53.192969Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:28:53.194102Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T16:28:53.196478Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"69895f7c3b831156","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:28:53.196788Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"69895f7c3b831156","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:28:53.197031Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:53.198108Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:53.198203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"info","ts":"2026-04-22T16:28:53.198342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:53.198885Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","added-peer-id":"69895f7c3b831156","added-peer-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-04-22T16:28:53.200955Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:28:53.201089Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-04-22T16:28:53.201143Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-04-22T16:28:53.201257Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"69895f7c3b831156","initial-advertise-peer-urls":["https://199.19.213.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:28:53.201307Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:28:54.186226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:28:54.186277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:28:54.186294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgPreVoteResp from 69895f7c3b831156 at term 1"} {"level":"info","ts":"2026-04-22T16:28:54.186308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:28:54.186315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgVoteResp from 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-04-22T16:28:54.186326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became leader at term 2"} {"level":"info","ts":"2026-04-22T16:28:54.186336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 69895f7c3b831156 elected leader 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-04-22T16:28:54.187585Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:54.188488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"69895f7c3b831156","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.179:2379]}","request-path":"/0/members/69895f7c3b831156/attributes","cluster-id":"caac76e5ff14ceff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:28:54.188506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:28:54.188586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:28:54.188779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:28:54.188856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:28:54.189363Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:54.189467Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:54.189501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:54.19418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.179:2379"} {"level":"info","ts":"2026-04-22T16:28:54.19467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:29:09.619571Z","caller":"traceutil/trace.go:171","msg":"trace[2020607482] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"135.675222ms","start":"2026-04-22T16:29:09.483852Z","end":"2026-04-22T16:29:09.619527Z","steps":["trace[2020607482] 'process raft request' (duration: 135.496687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:09.897452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.300457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:09.897575Z","caller":"traceutil/trace.go:171","msg":"trace[1630714130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"143.471322ms","start":"2026-04-22T16:29:09.754085Z","end":"2026-04-22T16:29:09.897557Z","steps":["trace[1630714130] 'range keys from in-memory index tree' (duration: 143.234286ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:29.853019Z","caller":"traceutil/trace.go:171","msg":"trace[1278974922] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"110.33113ms","start":"2026-04-22T16:29:29.742661Z","end":"2026-04-22T16:29:29.852992Z","steps":["trace[1278974922] 'process raft request' (duration: 110.044162ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.30553Z","caller":"traceutil/trace.go:171","msg":"trace[66547394] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"211.591166ms","start":"2026-04-22T16:29:33.093911Z","end":"2026-04-22T16:29:33.305502Z","steps":["trace[66547394] 'read index received' (duration: 209.384134ms)","trace[66547394] 'applied index is now lower than readState.Index' (duration: 2.205882ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:33.305636Z","caller":"traceutil/trace.go:171","msg":"trace[1557598035] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"449.835407ms","start":"2026-04-22T16:29:32.855775Z","end":"2026-04-22T16:29:33.305611Z","steps":["trace[1557598035] 'process raft request' (duration: 448.13524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.305842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.890774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:33.305869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.877504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:33.305941Z","caller":"traceutil/trace.go:171","msg":"trace[1124156578] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:569; }","duration":"212.023588ms","start":"2026-04-22T16:29:33.093859Z","end":"2026-04-22T16:29:33.305883Z","steps":["trace[1124156578] 'agreement among raft nodes before linearized reading' (duration: 211.792991ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.305962Z","caller":"traceutil/trace.go:171","msg":"trace[422927186] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:569; }","duration":"212.018307ms","start":"2026-04-22T16:29:33.093919Z","end":"2026-04-22T16:29:33.305938Z","steps":["trace[422927186] 'agreement among raft nodes before linearized reading' (duration: 211.872993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.306034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.913992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:33.306042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.277179ms","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":"info","ts":"2026-04-22T16:29:33.306146Z","caller":"traceutil/trace.go:171","msg":"trace[1399792371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:569; }","duration":"160.400503ms","start":"2026-04-22T16:29:33.145723Z","end":"2026-04-22T16:29:33.306124Z","steps":["trace[1399792371] 'agreement among raft nodes before linearized reading' (duration: 160.215638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.306155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.758303ms","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":"warn","ts":"2026-04-22T16:29:33.306172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.569349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:33.306178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.686794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:33.306227Z","caller":"traceutil/trace.go:171","msg":"trace[1801316350] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:569; }","duration":"138.775946ms","start":"2026-04-22T16:29:33.167437Z","end":"2026-04-22T16:29:33.306213Z","steps":["trace[1801316350] 'agreement among raft nodes before linearized reading' (duration: 138.678423ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.306228Z","caller":"traceutil/trace.go:171","msg":"trace[1322862828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:569; }","duration":"184.643051ms","start":"2026-04-22T16:29:33.121565Z","end":"2026-04-22T16:29:33.306208Z","steps":["trace[1322862828] 'agreement among raft nodes before linearized reading' (duration: 184.542218ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.306222Z","caller":"traceutil/trace.go:171","msg":"trace[946286472] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:569; }","duration":"160.860005ms","start":"2026-04-22T16:29:33.145343Z","end":"2026-04-22T16:29:33.306203Z","steps":["trace[946286472] 'agreement among raft nodes before linearized reading' (duration: 160.743762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.30607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.091495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:33.306239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.509437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:33.30756Z","caller":"traceutil/trace.go:171","msg":"trace[1002952534] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:569; }","duration":"138.234031ms","start":"2026-04-22T16:29:33.16809Z","end":"2026-04-22T16:29:33.306324Z","steps":["trace[1002952534] 'agreement among raft nodes before linearized reading' (duration: 137.853611ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.30763Z","caller":"traceutil/trace.go:171","msg":"trace[255128707] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:569; }","duration":"185.968578ms","start":"2026-04-22T16:29:33.121635Z","end":"2026-04-22T16:29:33.307603Z","steps":["trace[255128707] 'agreement among raft nodes before linearized reading' (duration: 184.508187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.306138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.824605ms","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":"warn","ts":"2026-04-22T16:29:33.307714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:32.855751Z","time spent":"449.94039ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:29:33.307954Z","caller":"traceutil/trace.go:171","msg":"trace[2027978228] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:569; }","duration":"162.645365ms","start":"2026-04-22T16:29:33.145276Z","end":"2026-04-22T16:29:33.307922Z","steps":["trace[2027978228] 'agreement among raft nodes before linearized reading' (duration: 160.834194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.308419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.097072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:33.308484Z","caller":"traceutil/trace.go:171","msg":"trace[405755709] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:569; }","duration":"141.175824ms","start":"2026-04-22T16:29:33.167281Z","end":"2026-04-22T16:29:33.308456Z","steps":["trace[405755709] 'agreement among raft nodes before linearized reading' (duration: 138.966862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:33.306274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.716609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:33.308743Z","caller":"traceutil/trace.go:171","msg":"trace[2104369425] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:569; }","duration":"186.789551ms","start":"2026-04-22T16:29:33.12191Z","end":"2026-04-22T16:29:33.308699Z","steps":["trace[2104369425] 'agreement among raft nodes before linearized reading' (duration: 184.079255ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:33.308779Z","caller":"traceutil/trace.go:171","msg":"trace[1267382031] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:569; }","duration":"214.177638ms","start":"2026-04-22T16:29:33.094533Z","end":"2026-04-22T16:29:33.308711Z","steps":["trace[1267382031] 'agreement among raft nodes before linearized reading' (duration: 211.673078ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:36.394333Z","caller":"traceutil/trace.go:171","msg":"trace[1671762713] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"171.310748ms","start":"2026-04-22T16:29:36.222995Z","end":"2026-04-22T16:29:36.394306Z","steps":["trace[1671762713] 'process raft request' (duration: 170.810014ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:36.403749Z","caller":"traceutil/trace.go:171","msg":"trace[957944980] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"118.425157ms","start":"2026-04-22T16:29:36.285298Z","end":"2026-04-22T16:29:36.403723Z","steps":["trace[957944980] 'read index received' (duration: 108.425387ms)","trace[957944980] 'applied index is now lower than readState.Index' (duration: 9.99872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:36.403934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.615922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mvgqk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T16:29:36.40397Z","caller":"traceutil/trace.go:171","msg":"trace[940313505] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mvgqk; range_end:; response_count:1; response_revision:589; }","duration":"118.707385ms","start":"2026-04-22T16:29:36.285253Z","end":"2026-04-22T16:29:36.40396Z","steps":["trace[940313505] 'agreement among raft nodes before linearized reading' (duration: 118.582441ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:36.404287Z","caller":"traceutil/trace.go:171","msg":"trace[1205777192] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"176.078782ms","start":"2026-04-22T16:29:36.228196Z","end":"2026-04-22T16:29:36.404275Z","steps":["trace[1205777192] 'process raft request' (duration: 175.410933ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:36.892315Z","caller":"traceutil/trace.go:171","msg":"trace[1193779455] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"423.563561ms","start":"2026-04-22T16:29:36.468721Z","end":"2026-04-22T16:29:36.892285Z","steps":["trace[1193779455] 'process raft request' (duration: 423.400956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:36.892504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.468696Z","time spent":"423.734696ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:29:36.892836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.8102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:29:36.892905Z","caller":"traceutil/trace.go:171","msg":"trace[1915539940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"302.924434ms","start":"2026-04-22T16:29:36.589966Z","end":"2026-04-22T16:29:36.892891Z","steps":["trace[1915539940] 'agreement among raft nodes before linearized reading' (duration: 302.705417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:36.892953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.58995Z","time spent":"302.995115ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:29:36.892645Z","caller":"traceutil/trace.go:171","msg":"trace[478191004] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:615; }","duration":"302.274965ms","start":"2026-04-22T16:29:36.590005Z","end":"2026-04-22T16:29:36.89228Z","steps":["trace[478191004] 'read index received' (duration: 302.258875ms)","trace[478191004] 'applied index is now lower than readState.Index' (duration: 12.96µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:36.95472Z","caller":"traceutil/trace.go:171","msg":"trace[1878901838] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"326.03132ms","start":"2026-04-22T16:29:36.628664Z","end":"2026-04-22T16:29:36.954695Z","steps":["trace[1878901838] 'process raft request' (duration: 325.858836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:36.954952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.376163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:36.954998Z","caller":"traceutil/trace.go:171","msg":"trace[14653884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"159.470326ms","start":"2026-04-22T16:29:36.795518Z","end":"2026-04-22T16:29:36.954989Z","steps":["trace[14653884] 'agreement among raft nodes before linearized reading' (duration: 159.393424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:36.955116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.895474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T16:29:36.955152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.628637Z","time spent":"326.161344ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:36.955196Z","caller":"traceutil/trace.go:171","msg":"trace[291189619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"182.022868ms","start":"2026-04-22T16:29:36.773153Z","end":"2026-04-22T16:29:36.955175Z","steps":["trace[291189619] 'agreement among raft nodes before linearized reading' (duration: 181.799102ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:52.078671Z","caller":"traceutil/trace.go:171","msg":"trace[1775882615] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"112.047938ms","start":"2026-04-22T16:29:51.966599Z","end":"2026-04-22T16:29:52.078647Z","steps":["trace[1775882615] 'process raft request' (duration: 111.902884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:19.171059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.573021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:30:19.171195Z","caller":"traceutil/trace.go:171","msg":"trace[183222115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1145; }","duration":"203.790958ms","start":"2026-04-22T16:30:18.967377Z","end":"2026-04-22T16:30:19.171168Z","steps":["trace[183222115] 'range keys from in-memory index tree' (duration: 203.268052ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:05.965954Z","caller":"traceutil/trace.go:171","msg":"trace[1149144305] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"169.882585ms","start":"2026-04-22T16:31:05.796046Z","end":"2026-04-22T16:31:05.965928Z","steps":["trace[1149144305] 'read index received' (duration: 153.704039ms)","trace[1149144305] 'applied index is now lower than readState.Index' (duration: 16.177906ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:05.966065Z","caller":"traceutil/trace.go:171","msg":"trace[41508044] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"174.255896ms","start":"2026-04-22T16:31:05.791758Z","end":"2026-04-22T16:31:05.966014Z","steps":["trace[41508044] 'process raft request' (duration: 157.909505ms)","trace[41508044] 'compare' (duration: 16.169276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:31:05.966145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.913224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-22T16:31:05.966141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.078991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:31:05.966229Z","caller":"traceutil/trace.go:171","msg":"trace[2035208029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1817; }","duration":"170.213055ms","start":"2026-04-22T16:31:05.795993Z","end":"2026-04-22T16:31:05.966206Z","steps":["trace[2035208029] 'agreement among raft nodes before linearized reading' (duration: 170.03638ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:05.966173Z","caller":"traceutil/trace.go:171","msg":"trace[820344056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1817; }","duration":"133.984576ms","start":"2026-04-22T16:31:05.832179Z","end":"2026-04-22T16:31:05.966163Z","steps":["trace[820344056] 'agreement among raft nodes before linearized reading' (duration: 133.878873ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:17.677753Z","caller":"traceutil/trace.go:171","msg":"trace[1531284322] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"137.543374ms","start":"2026-04-22T16:31:17.54019Z","end":"2026-04-22T16:31:17.677733Z","steps":["trace[1531284322] 'read index received' (duration: 137.40075ms)","trace[1531284322] 'applied index is now lower than readState.Index' (duration: 142.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:17.677809Z","caller":"traceutil/trace.go:171","msg":"trace[1170482506] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"138.15254ms","start":"2026-04-22T16:31:17.539631Z","end":"2026-04-22T16:31:17.677784Z","steps":["trace[1170482506] 'process raft request' (duration: 137.982896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:17.678173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.972085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:31:17.678242Z","caller":"traceutil/trace.go:171","msg":"trace[1086067518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1917; }","duration":"138.099968ms","start":"2026-04-22T16:31:17.540126Z","end":"2026-04-22T16:31:17.678226Z","steps":["trace[1086067518] 'agreement among raft nodes before linearized reading' (duration: 137.689017ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:54.79584Z","caller":"traceutil/trace.go:171","msg":"trace[1239473585] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"125.002194ms","start":"2026-04-22T16:31:54.670787Z","end":"2026-04-22T16:31:54.79579Z","steps":["trace[1239473585] 'process raft request' (duration: 124.820999ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:33:58.583269Z","caller":"traceutil/trace.go:171","msg":"trace[29743121] transaction","detail":"{read_only:false; response_revision:2999; number_of_response:1; }","duration":"244.624882ms","start":"2026-04-22T16:33:58.338623Z","end":"2026-04-22T16:33:58.583248Z","steps":["trace[29743121] 'process raft request' (duration: 244.466838ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:59.843685Z","caller":"traceutil/trace.go:171","msg":"trace[218524092] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4930; }","duration":"118.23388ms","start":"2026-04-22T16:36:59.725422Z","end":"2026-04-22T16:36:59.843656Z","steps":["trace[218524092] 'read index received' (duration: 94.118551ms)","trace[218524092] 'applied index is now lower than readState.Index' (duration: 24.114179ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:36:59.843705Z","caller":"traceutil/trace.go:171","msg":"trace[1296268886] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"123.890317ms","start":"2026-04-22T16:36:59.719756Z","end":"2026-04-22T16:36:59.843646Z","steps":["trace[1296268886] 'process raft request' (duration: 99.765017ms)","trace[1296268886] 'compare' (duration: 23.816611ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:36:59.843784Z","caller":"traceutil/trace.go:171","msg":"trace[358989413] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"118.851047ms","start":"2026-04-22T16:36:59.724892Z","end":"2026-04-22T16:36:59.843743Z","steps":["trace[358989413] 'process raft request' (duration: 118.672232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:59.843972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.412551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-22T16:36:59.844021Z","caller":"traceutil/trace.go:171","msg":"trace[170226049] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4750; }","duration":"128.532526ms","start":"2026-04-22T16:36:59.715476Z","end":"2026-04-22T16:36:59.844008Z","steps":["trace[170226049] 'agreement among raft nodes before linearized reading' (duration: 128.32529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:59.84405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.215076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62866"} {"level":"warn","ts":"2026-04-22T16:36:59.844135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.605327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-22T16:36:59.844152Z","caller":"traceutil/trace.go:171","msg":"trace[1103013976] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4750; }","duration":"124.36228ms","start":"2026-04-22T16:36:59.719768Z","end":"2026-04-22T16:36:59.84413Z","steps":["trace[1103013976] 'agreement among raft nodes before linearized reading' (duration: 123.937368ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:59.844179Z","caller":"traceutil/trace.go:171","msg":"trace[868689577] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4750; }","duration":"128.70307ms","start":"2026-04-22T16:36:59.715464Z","end":"2026-04-22T16:36:59.844167Z","steps":["trace[868689577] 'agreement among raft nodes before linearized reading' (duration: 128.512195ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:59.852146Z","caller":"traceutil/trace.go:171","msg":"trace[1860086826] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"112.579752ms","start":"2026-04-22T16:36:59.739532Z","end":"2026-04-22T16:36:59.852111Z","steps":["trace[1860086826] 'process raft request' (duration: 112.248883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:59.852726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.845432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-22T16:36:59.852821Z","caller":"traceutil/trace.go:171","msg":"trace[2012871004] 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:4753; }","duration":"111.957486ms","start":"2026-04-22T16:36:59.740823Z","end":"2026-04-22T16:36:59.85278Z","steps":["trace[2012871004] 'agreement among raft nodes before linearized reading' (duration: 111.475712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.630147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.928405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T16:37:03.630184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.710627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T16:37:03.630257Z","caller":"traceutil/trace.go:171","msg":"trace[1726038426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4896; }","duration":"187.056158ms","start":"2026-04-22T16:37:03.443178Z","end":"2026-04-22T16:37:03.630234Z","steps":["trace[1726038426] 'range keys from in-memory index tree' (duration: 186.205835ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.630292Z","caller":"traceutil/trace.go:171","msg":"trace[38383348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4896; }","duration":"178.83555ms","start":"2026-04-22T16:37:03.451435Z","end":"2026-04-22T16:37:03.63027Z","steps":["trace[38383348] 'range keys from in-memory index tree' (duration: 178.531082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.630275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.49592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f603a04f-5279-4dbd-abbc-e0ef57e156b6\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-22T16:37:03.630372Z","caller":"traceutil/trace.go:171","msg":"trace[1312401061] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f603a04f-5279-4dbd-abbc-e0ef57e156b6; range_end:; response_count:1; response_revision:4896; }","duration":"170.621793ms","start":"2026-04-22T16:37:03.459729Z","end":"2026-04-22T16:37:03.630351Z","steps":["trace[1312401061] 'range keys from in-memory index tree' (duration: 170.301784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.630791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.894527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T16:37:03.630857Z","caller":"traceutil/trace.go:171","msg":"trace[2039063452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4896; }","duration":"106.00169ms","start":"2026-04-22T16:37:03.52484Z","end":"2026-04-22T16:37:03.630841Z","steps":["trace[2039063452] 'range keys from in-memory index tree' (duration: 105.583028ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.743612Z","caller":"traceutil/trace.go:171","msg":"trace[1858993973] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"108.006716ms","start":"2026-04-22T16:37:03.635577Z","end":"2026-04-22T16:37:03.743584Z","steps":["trace[1858993973] 'process raft request' (duration: 103.035308ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.744016Z","caller":"traceutil/trace.go:171","msg":"trace[1669679278] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"106.079693ms","start":"2026-04-22T16:37:03.637915Z","end":"2026-04-22T16:37:03.743995Z","steps":["trace[1669679278] 'process raft request' (duration: 105.597489ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.744448Z","caller":"traceutil/trace.go:171","msg":"trace[1517830786] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"105.245709ms","start":"2026-04-22T16:37:03.639194Z","end":"2026-04-22T16:37:03.74444Z","steps":["trace[1517830786] 'process raft request' (duration: 104.57578ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:09.700293Z","caller":"traceutil/trace.go:171","msg":"trace[1456907657] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5233; }","duration":"177.73928ms","start":"2026-04-22T16:37:09.522529Z","end":"2026-04-22T16:37:09.700268Z","steps":["trace[1456907657] 'read index received' (duration: 177.495703ms)","trace[1456907657] 'applied index is now lower than readState.Index' (duration: 242.837µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:09.70038Z","caller":"traceutil/trace.go:171","msg":"trace[1498033317] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"194.179716ms","start":"2026-04-22T16:37:09.506158Z","end":"2026-04-22T16:37:09.700337Z","steps":["trace[1498033317] 'process raft request' (duration: 193.96346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:09.700472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.922285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-lkw7l\" ","response":"range_response_count:1 size:4444"} {"level":"info","ts":"2026-04-22T16:37:09.70071Z","caller":"traceutil/trace.go:171","msg":"trace[2141802690] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-lkw7l; range_end:; response_count:1; response_revision:5043; }","duration":"178.190583ms","start":"2026-04-22T16:37:09.522497Z","end":"2026-04-22T16:37:09.700688Z","steps":["trace[2141802690] 'agreement among raft nodes before linearized reading' (duration: 177.869684ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:21.485701Z","caller":"traceutil/trace.go:171","msg":"trace[137929364] linearizableReadLoop","detail":"{readStateIndex:5423; appliedIndex:5421; }","duration":"121.621973ms","start":"2026-04-22T16:37:21.364053Z","end":"2026-04-22T16:37:21.485675Z","steps":["trace[137929364] 'read index received' (duration: 78.621881ms)","trace[137929364] 'applied index is now lower than readState.Index' (duration: 42.998692ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:21.485743Z","caller":"traceutil/trace.go:171","msg":"trace[394691875] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"122.775345ms","start":"2026-04-22T16:37:21.362926Z","end":"2026-04-22T16:37:21.485701Z","steps":["trace[394691875] 'process raft request' (duration: 122.620541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:21.485919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.845839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-22T16:37:21.485962Z","caller":"traceutil/trace.go:171","msg":"trace[1237102673] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5230; }","duration":"121.922282ms","start":"2026-04-22T16:37:21.364027Z","end":"2026-04-22T16:37:21.485949Z","steps":["trace[1237102673] 'agreement among raft nodes before linearized reading' (duration: 121.755177ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:21.486256Z","caller":"traceutil/trace.go:171","msg":"trace[932779802] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"116.837811ms","start":"2026-04-22T16:37:21.369405Z","end":"2026-04-22T16:37:21.486242Z","steps":["trace[932779802] 'process raft request' (duration: 116.226284ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:21.485503Z","caller":"traceutil/trace.go:171","msg":"trace[1123016977] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"122.797305ms","start":"2026-04-22T16:37:21.362669Z","end":"2026-04-22T16:37:21.485467Z","steps":["trace[1123016977] 'process raft request' (duration: 80.022239ms)","trace[1123016977] 'compare' (duration: 42.278703ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:22.84837Z","caller":"traceutil/trace.go:171","msg":"trace[447119640] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"170.009096ms","start":"2026-04-22T16:37:22.678327Z","end":"2026-04-22T16:37:22.848336Z","steps":["trace[447119640] 'process raft request' (duration: 169.79531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:36.051611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.854129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T16:37:36.051751Z","caller":"traceutil/trace.go:171","msg":"trace[1909584675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5460; }","duration":"140.063575ms","start":"2026-04-22T16:37:35.911663Z","end":"2026-04-22T16:37:36.051726Z","steps":["trace[1909584675] 'range keys from in-memory index tree' (duration: 139.640484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:36.051943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.387942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T16:37:36.05208Z","caller":"traceutil/trace.go:171","msg":"trace[206755290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5460; }","duration":"101.524446ms","start":"2026-04-22T16:37:35.950519Z","end":"2026-04-22T16:37:36.052043Z","steps":["trace[206755290] 'range keys from in-memory index tree' (duration: 101.091434ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:58.198498Z","caller":"traceutil/trace.go:171","msg":"trace[1635726637] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"160.77967ms","start":"2026-04-22T16:37:58.037686Z","end":"2026-04-22T16:37:58.198466Z","steps":["trace[1635726637] 'process raft request' (duration: 160.633946ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:38:54.224984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-04-22T16:38:54.294287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"67.829702ms","hash":3155867496,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T16:38:54.29438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155867496,"revision":2975,"compact-revision":-1} {"level":"info","ts":"2026-04-22T16:43:34.457454Z","caller":"traceutil/trace.go:171","msg":"trace[1018470888] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"114.7244ms","start":"2026-04-22T16:43:34.342707Z","end":"2026-04-22T16:43:34.457431Z","steps":["trace[1018470888] 'process raft request' (duration: 114.575226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:54.231268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-04-22T16:43:54.335495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"102.535665ms","hash":3500397363,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":22994944,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-22T16:43:54.335601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3500397363,"revision":6103,"compact-revision":2975} {"level":"info","ts":"2026-04-22T16:44:03.562655Z","caller":"traceutil/trace.go:171","msg":"trace[340310308] transaction","detail":"{read_only:false; response_revision:8376; number_of_response:1; }","duration":"103.803921ms","start":"2026-04-22T16:44:03.458832Z","end":"2026-04-22T16:44:03.562635Z","steps":["trace[340310308] 'process raft request' (duration: 103.652607ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:06.238014Z","caller":"traceutil/trace.go:171","msg":"trace[1623699300] linearizableReadLoop","detail":"{readStateIndex:8683; appliedIndex:8682; }","duration":"122.905883ms","start":"2026-04-22T16:44:06.115085Z","end":"2026-04-22T16:44:06.237991Z","steps":["trace[1623699300] 'read index received' (duration: 76.532654ms)","trace[1623699300] 'applied index is now lower than readState.Index' (duration: 46.372439ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:06.238069Z","caller":"traceutil/trace.go:171","msg":"trace[924096225] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"139.771905ms","start":"2026-04-22T16:44:06.098247Z","end":"2026-04-22T16:44:06.238018Z","steps":["trace[924096225] 'process raft request' (duration: 93.377745ms)","trace[924096225] 'compare' (duration: 46.245206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:44:06.238215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.088748ms","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-04-22T16:44:06.238258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.488257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T16:44:06.238291Z","caller":"traceutil/trace.go:171","msg":"trace[1657424082] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8389; }","duration":"120.546659ms","start":"2026-04-22T16:44:06.117735Z","end":"2026-04-22T16:44:06.238282Z","steps":["trace[1657424082] 'agreement among raft nodes before linearized reading' (duration: 120.384555ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:06.238298Z","caller":"traceutil/trace.go:171","msg":"trace[1448360385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8389; }","duration":"123.219902ms","start":"2026-04-22T16:44:06.115061Z","end":"2026-04-22T16:44:06.238281Z","steps":["trace[1448360385] 'agreement among raft nodes before linearized reading' (duration: 123.009916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:16.183623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.807917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:44:16.183702Z","caller":"traceutil/trace.go:171","msg":"trace[401285701] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8486; }","duration":"112.932431ms","start":"2026-04-22T16:44:16.070754Z","end":"2026-04-22T16:44:16.183686Z","steps":["trace[401285701] 'range keys from in-memory index tree' (duration: 112.676913ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:16.30787Z","caller":"traceutil/trace.go:171","msg":"trace[899774970] linearizableReadLoop","detail":"{readStateIndex:8786; appliedIndex:8785; }","duration":"117.814704ms","start":"2026-04-22T16:44:16.190033Z","end":"2026-04-22T16:44:16.307847Z","steps":["trace[899774970] 'read index received' (duration: 117.585778ms)","trace[899774970] 'applied index is now lower than readState.Index' (duration: 227.916µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:16.307955Z","caller":"traceutil/trace.go:171","msg":"trace[48951119] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"119.747377ms","start":"2026-04-22T16:44:16.188168Z","end":"2026-04-22T16:44:16.307916Z","steps":["trace[48951119] 'process raft request' (duration: 119.528311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:16.30823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.180114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-22T16:44:16.308301Z","caller":"traceutil/trace.go:171","msg":"trace[17331905] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8487; }","duration":"118.284147ms","start":"2026-04-22T16:44:16.19Z","end":"2026-04-22T16:44:16.308284Z","steps":["trace[17331905] 'agreement among raft nodes before linearized reading' (duration: 117.973388ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:16.475313Z","caller":"traceutil/trace.go:171","msg":"trace[639534480] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"161.649044ms","start":"2026-04-22T16:44:16.313637Z","end":"2026-04-22T16:44:16.475286Z","steps":["trace[639534480] 'process raft request' (duration: 94.968779ms)","trace[639534480] 'compare' (duration: 66.442098ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:16.475506Z","caller":"traceutil/trace.go:171","msg":"trace[617396791] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"161.679224ms","start":"2026-04-22T16:44:16.313815Z","end":"2026-04-22T16:44:16.475494Z","steps":["trace[617396791] 'process raft request' (duration: 161.388246ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:16.475506Z","caller":"traceutil/trace.go:171","msg":"trace[155949129] linearizableReadLoop","detail":"{readStateIndex:8789; appliedIndex:8786; }","duration":"155.428963ms","start":"2026-04-22T16:44:16.320044Z","end":"2026-04-22T16:44:16.475473Z","steps":["trace[155949129] 'read index received' (duration: 57.312838ms)","trace[155949129] 'applied index is now lower than readState.Index' (duration: 98.114295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:44:16.475766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.6995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-22T16:44:16.476801Z","caller":"traceutil/trace.go:171","msg":"trace[384185803] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8490; }","duration":"156.749639ms","start":"2026-04-22T16:44:16.320012Z","end":"2026-04-22T16:44:16.476762Z","steps":["trace[384185803] 'agreement among raft nodes before linearized reading' (duration: 155.549926ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:17.147012Z","caller":"traceutil/trace.go:171","msg":"trace[289356910] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"120.376794ms","start":"2026-04-22T16:44:17.026605Z","end":"2026-04-22T16:44:17.146982Z","steps":["trace[289356910] 'process raft request' (duration: 120.174589ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:17.150362Z","caller":"traceutil/trace.go:171","msg":"trace[1260216772] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"104.769157ms","start":"2026-04-22T16:44:17.045572Z","end":"2026-04-22T16:44:17.150341Z","steps":["trace[1260216772] 'process raft request' (duration: 104.669474ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:06.580898Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"} {"level":"info","ts":"2026-04-22T16:46:32.468973Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"69895f7c3b831156","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T16:46:32.475455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T16:46:32.475822Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-22T16:48:05.294883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249359351750501847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:48:05.795318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249359351750501847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:48:05.846327Z","caller":"traceutil/trace.go:171","msg":"trace[2057045214] transaction","detail":"{read_only:false; response_revision:10395; number_of_response:1; }","duration":"1.274550282s","start":"2026-04-22T16:48:04.571755Z","end":"2026-04-22T16:48:05.846306Z","steps":["trace[2057045214] 'process raft request' (duration: 1.274415749s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.846448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.571726Z","time spent":"1.274656995s","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:48:05.853494Z","caller":"traceutil/trace.go:171","msg":"trace[162247857] linearizableReadLoop","detail":"{readStateIndex:10753; appliedIndex:10751; }","duration":"1.059192935s","start":"2026-04-22T16:48:04.794278Z","end":"2026-04-22T16:48:05.853471Z","steps":["trace[162247857] 'read index received' (duration: 1.051896865s)","trace[162247857] 'applied index is now lower than readState.Index' (duration: 7.29513ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:48:05.853717Z","caller":"traceutil/trace.go:171","msg":"trace[2092724872] transaction","detail":"{read_only:false; response_revision:10396; number_of_response:1; }","duration":"1.117170752s","start":"2026-04-22T16:48:04.736434Z","end":"2026-04-22T16:48:05.853605Z","steps":["trace[2092724872] 'process raft request' (duration: 1.116880424s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.853816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.103866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"warn","ts":"2026-04-22T16:48:05.853842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.5747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T16:48:05.853886Z","caller":"traceutil/trace.go:171","msg":"trace[2118529344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10396; }","duration":"121.679163ms","start":"2026-04-22T16:48:05.732198Z","end":"2026-04-22T16:48:05.853877Z","steps":["trace[2118529344] 'agreement among raft nodes before linearized reading' (duration: 121.488197ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:48:05.85389Z","caller":"traceutil/trace.go:171","msg":"trace[925480698] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:10396; }","duration":"855.22171ms","start":"2026-04-22T16:48:04.998652Z","end":"2026-04-22T16:48:05.853873Z","steps":["trace[925480698] 'agreement among raft nodes before linearized reading' (duration: 855.001814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.85388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.736409Z","time spent":"1.117371478s","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:05.853943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.998634Z","time spent":"855.299071ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5152,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-22T16:48:05.853913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059613837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:05.854003Z","caller":"traceutil/trace.go:171","msg":"trace[1868647221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10396; }","duration":"1.059714279s","start":"2026-04-22T16:48:04.794262Z","end":"2026-04-22T16:48:05.853976Z","steps":["trace[1868647221] 'agreement among raft nodes before linearized reading' (duration: 1.059548175s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.854023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.514151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:05.854046Z","caller":"traceutil/trace.go:171","msg":"trace[278702840] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:10396; }","duration":"353.582962ms","start":"2026-04-22T16:48:05.500457Z","end":"2026-04-22T16:48:05.85404Z","steps":["trace[278702840] 'agreement among raft nodes before linearized reading' (duration: 353.541821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.854088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:05.500433Z","time spent":"353.648004ms","remote":"127.0.0.1:40832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:48:05.85407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.794199Z","time spent":"1.059853484s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:48:05.854161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.073203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T16:48:05.854261Z","caller":"traceutil/trace.go:171","msg":"trace[2008594573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10396; }","duration":"187.204247ms","start":"2026-04-22T16:48:05.667036Z","end":"2026-04-22T16:48:05.854241Z","steps":["trace[2008594573] 'agreement among raft nodes before linearized reading' (duration: 186.878848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.854466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.954334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T16:48:05.854544Z","caller":"traceutil/trace.go:171","msg":"trace[2092121908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10396; }","duration":"231.104459ms","start":"2026-04-22T16:48:05.623424Z","end":"2026-04-22T16:48:05.854529Z","steps":["trace[2092121908] 'agreement among raft nodes before linearized reading' (duration: 230.333977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:07.609661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.985128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19102"} {"level":"info","ts":"2026-04-22T16:48:07.609777Z","caller":"traceutil/trace.go:171","msg":"trace[2024960500] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10411; }","duration":"257.126851ms","start":"2026-04-22T16:48:07.352622Z","end":"2026-04-22T16:48:07.609749Z","steps":["trace[2024960500] 'range keys from in-memory index tree' (duration: 256.780341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:07.610226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.132059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:48:07.61027Z","caller":"traceutil/trace.go:171","msg":"trace[122764218] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:10411; }","duration":"235.203431ms","start":"2026-04-22T16:48:07.375051Z","end":"2026-04-22T16:48:07.610255Z","steps":["trace[122764218] 'count revisions from in-memory index tree' (duration: 235.074487ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:48:08.398315Z","caller":"traceutil/trace.go:171","msg":"trace[1421389827] linearizableReadLoop","detail":"{readStateIndex:10770; appliedIndex:10769; }","duration":"320.374354ms","start":"2026-04-22T16:48:08.077922Z","end":"2026-04-22T16:48:08.398297Z","steps":["trace[1421389827] 'read index received' (duration: 1.45675ms)","trace[1421389827] 'applied index is now lower than readState.Index' (duration: 318.916814ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:48:08.398522Z","caller":"traceutil/trace.go:171","msg":"trace[1619639970] transaction","detail":"{read_only:false; response_revision:10412; number_of_response:1; }","duration":"320.60793ms","start":"2026-04-22T16:48:08.077907Z","end":"2026-04-22T16:48:08.398515Z","steps":["trace[1619639970] 'process raft request' (duration: 320.25612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.3986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.077895Z","time spent":"320.64914ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:08.398701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.764984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T16:48:08.398751Z","caller":"traceutil/trace.go:171","msg":"trace[97287305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10412; }","duration":"320.829006ms","start":"2026-04-22T16:48:08.077912Z","end":"2026-04-22T16:48:08.398741Z","steps":["trace[97287305] 'agreement among raft nodes before linearized reading' (duration: 320.692092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.398771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.077908Z","time spent":"320.858896ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T16:48:08.405348Z","caller":"traceutil/trace.go:171","msg":"trace[1114111042] transaction","detail":"{read_only:false; response_revision:10416; number_of_response:1; }","duration":"317.515405ms","start":"2026-04-22T16:48:08.087809Z","end":"2026-04-22T16:48:08.405324Z","steps":["trace[1114111042] 'process raft request' (duration: 317.483074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.405481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.087801Z","time spent":"317.612868ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:48:08.405815Z","caller":"traceutil/trace.go:171","msg":"trace[934605091] transaction","detail":"{read_only:false; response_revision:10413; number_of_response:1; }","duration":"325.168585ms","start":"2026-04-22T16:48:08.080638Z","end":"2026-04-22T16:48:08.405807Z","steps":["trace[934605091] 'process raft request' (duration: 324.432504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.405857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.080628Z","time spent":"325.207115ms","remote":"127.0.0.1:49210","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-04-22T16:48:08.405941Z","caller":"traceutil/trace.go:171","msg":"trace[737298100] transaction","detail":"{read_only:false; response_revision:10414; number_of_response:1; }","duration":"323.273433ms","start":"2026-04-22T16:48:08.082661Z","end":"2026-04-22T16:48:08.405934Z","steps":["trace[737298100] 'process raft request' (duration: 322.522712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.405977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.082654Z","time spent":"323.304674ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:48:08.406047Z","caller":"traceutil/trace.go:171","msg":"trace[910746503] transaction","detail":"{read_only:false; response_revision:10415; number_of_response:1; }","duration":"323.357645ms","start":"2026-04-22T16:48:08.082672Z","end":"2026-04-22T16:48:08.406029Z","steps":["trace[910746503] 'process raft request' (duration: 322.541412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.40615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.082666Z","time spent":"323.427347ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:08.406429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.808457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19102"} {"level":"info","ts":"2026-04-22T16:48:08.406454Z","caller":"traceutil/trace.go:171","msg":"trace[218001974] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10416; }","duration":"323.847288ms","start":"2026-04-22T16:48:08.0826Z","end":"2026-04-22T16:48:08.406447Z","steps":["trace[218001974] 'agreement among raft nodes before linearized reading' (duration: 323.752655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.406472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.082594Z","time spent":"323.87429ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19125,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-22T16:48:08.406669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.486265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T16:48:08.406749Z","caller":"traceutil/trace.go:171","msg":"trace[1748019476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10416; }","duration":"328.579538ms","start":"2026-04-22T16:48:08.078148Z","end":"2026-04-22T16:48:08.406728Z","steps":["trace[1748019476] 'agreement among raft nodes before linearized reading' (duration: 328.331561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.406789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.078144Z","time spent":"328.63713ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:48:08.407039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.084412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T16:48:08.407075Z","caller":"traceutil/trace.go:171","msg":"trace[1261453547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10416; }","duration":"329.133193ms","start":"2026-04-22T16:48:08.077933Z","end":"2026-04-22T16:48:08.407066Z","steps":["trace[1261453547] 'agreement among raft nodes before linearized reading' (duration: 329.03336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.407103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.07793Z","time spent":"329.166474ms","remote":"127.0.0.1:49210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:48:08.407385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.052154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-04-22T16:48:08.407417Z","caller":"traceutil/trace.go:171","msg":"trace[1960259723] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:10418; }","duration":"328.096005ms","start":"2026-04-22T16:48:08.079312Z","end":"2026-04-22T16:48:08.407408Z","steps":["trace[1960259723] 'agreement among raft nodes before linearized reading' (duration: 328.047924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.407435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.079306Z","time spent":"328.124156ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5152,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"info","ts":"2026-04-22T16:48:54.237498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8279} {"level":"info","ts":"2026-04-22T16:48:54.318443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8279,"took":"78.948572ms","hash":800320449,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":24850432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-22T16:48:54.318528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":800320449,"revision":8279,"compact-revision":6103} {"level":"info","ts":"2026-04-22T16:53:54.247859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10666} {"level":"info","ts":"2026-04-22T16:53:54.327181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10666,"took":"77.306224ms","hash":3744628816,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":20246528,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-22T16:53:54.32725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3744628816,"revision":10666,"compact-revision":8279}