{"level":"warn","ts":"2026-05-19T22:19:23.040269Z","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-19T22:19:23.040415Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-19T22:19:23.040513Z","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-19T22:19:23.040535Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-19T22:19:23.040563Z","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-19T22:19:23.041351Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-19T22:19:23.041469Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-19T22:19:23.045415Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.665855ms"} {"level":"info","ts":"2026-05-19T22:19:23.05144Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-19T22:19:23.051546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T22:19:23.051572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-19T22:19:23.051587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T22:19:23.051598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-19T22:19:23.051679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-19T22:19:23.056293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T22:19:23.057679Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T22:19:23.059015Z","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-19T22:19:23.061219Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T22:19:23.061335Z","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-19T22:19:23.061424Z","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-19T22:19:23.061443Z","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-19T22:19:23.061357Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T22:19:23.063458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-19T22:19:23.065243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-19T22:19:23.067251Z","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-19T22:19:23.067381Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-19T22:19:23.06743Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-19T22:19:23.067594Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T22:19:23.067652Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T22:19:23.952294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T22:19:23.952358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T22:19:23.952395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-19T22:19:23.952415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-19T22:19:23.952427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-19T22:19:23.952563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-19T22:19:23.952578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-19T22:19:23.953714Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T22:19:23.953731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:23.953772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:23.953765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:23.954201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T22:19:23.954449Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T22:19:23.955676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:23.955837Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:23.955906Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:23.957581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T22:19:23.958207Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-05-19T22:19:27.133268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.556786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:27.133607Z","caller":"traceutil/trace.go:171","msg":"trace[1222894464] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"125.274931ms","start":"2026-05-19T22:19:27.008306Z","end":"2026-05-19T22:19:27.133581Z","steps":["trace[1222894464] 'read index received' (duration: 5.57063ms)","trace[1222894464] 'applied index is now lower than readState.Index' (duration: 119.702631ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:27.133685Z","caller":"traceutil/trace.go:171","msg":"trace[1426846225] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"178.362346ms","start":"2026-05-19T22:19:26.955291Z","end":"2026-05-19T22:19:27.133654Z","steps":["trace[1426846225] 'process raft request' (duration: 58.678076ms)","trace[1426846225] 'compare' (duration: 118.390602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:27.133865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.542748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:19:27.13417Z","caller":"traceutil/trace.go:171","msg":"trace[1487234413] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:213; }","duration":"125.877208ms","start":"2026-05-19T22:19:27.008274Z","end":"2026-05-19T22:19:27.134151Z","steps":["trace[1487234413] 'agreement among raft nodes before linearized reading' (duration: 125.445066ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:41.328615Z","caller":"traceutil/trace.go:171","msg":"trace[458392962] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"110.895627ms","start":"2026-05-19T22:19:41.217689Z","end":"2026-05-19T22:19:41.328584Z","steps":["trace[458392962] 'process raft request' (duration: 110.159536ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.146653Z","caller":"traceutil/trace.go:171","msg":"trace[349348746] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"204.173038ms","start":"2026-05-19T22:20:02.942453Z","end":"2026-05-19T22:20:03.146626Z","steps":["trace[349348746] 'read index received' (duration: 203.238501ms)","trace[349348746] 'applied index is now lower than readState.Index' (duration: 933.497µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:20:03.14672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.2466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.146774Z","caller":"traceutil/trace.go:171","msg":"trace[1462370727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"204.340273ms","start":"2026-05-19T22:20:02.942421Z","end":"2026-05-19T22:20:03.146761Z","steps":["trace[1462370727] 'agreement among raft nodes before linearized reading' (duration: 204.222529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.146821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.348272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.146867Z","caller":"traceutil/trace.go:171","msg":"trace[1722156984] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"204.445795ms","start":"2026-05-19T22:20:02.942408Z","end":"2026-05-19T22:20:03.146854Z","steps":["trace[1722156984] 'agreement among raft nodes before linearized reading' (duration: 204.359733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.147032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.824753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.147116Z","caller":"traceutil/trace.go:171","msg":"trace[42803215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"166.968097ms","start":"2026-05-19T22:20:02.980127Z","end":"2026-05-19T22:20:03.147095Z","steps":["trace[42803215] 'agreement among raft nodes before linearized reading' (duration: 166.804762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.147208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.275933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.147228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.781794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.147247Z","caller":"traceutil/trace.go:171","msg":"trace[1704476598] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:622; }","duration":"131.348775ms","start":"2026-05-19T22:20:03.015885Z","end":"2026-05-19T22:20:03.147234Z","steps":["trace[1704476598] 'agreement among raft nodes before linearized reading' (duration: 131.294303ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.147254Z","caller":"traceutil/trace.go:171","msg":"trace[1182020472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"165.825615ms","start":"2026-05-19T22:20:02.981421Z","end":"2026-05-19T22:20:03.147246Z","steps":["trace[1182020472] 'agreement among raft nodes before linearized reading' (duration: 165.787814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.147321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.110111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.147338Z","caller":"traceutil/trace.go:171","msg":"trace[1879457571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"167.158763ms","start":"2026-05-19T22:20:02.980173Z","end":"2026-05-19T22:20:03.147332Z","steps":["trace[1879457571] 'agreement among raft nodes before linearized reading' (duration: 167.133202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.147355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.493139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.147401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.406287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.147441Z","caller":"traceutil/trace.go:171","msg":"trace[718658972] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:622; }","duration":"130.623453ms","start":"2026-05-19T22:20:03.016795Z","end":"2026-05-19T22:20:03.147418Z","steps":["trace[718658972] 'agreement among raft nodes before linearized reading' (duration: 130.49924ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.147473Z","caller":"traceutil/trace.go:171","msg":"trace[1585280895] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:622; }","duration":"131.516609ms","start":"2026-05-19T22:20:03.015936Z","end":"2026-05-19T22:20:03.147452Z","steps":["trace[1585280895] 'agreement among raft nodes before linearized reading' (duration: 131.409026ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.147604Z","caller":"traceutil/trace.go:171","msg":"trace[383363301] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"226.373744ms","start":"2026-05-19T22:20:02.921216Z","end":"2026-05-19T22:20:03.14759Z","steps":["trace[383363301] 'process raft request' (duration: 224.707697ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.330673Z","caller":"traceutil/trace.go:171","msg":"trace[974103511] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"125.201389ms","start":"2026-05-19T22:20:03.205451Z","end":"2026-05-19T22:20:03.330652Z","steps":["trace[974103511] 'process raft request' (duration: 121.77236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.870781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.358147ms","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-19T22:20:03.870863Z","caller":"traceutil/trace.go:171","msg":"trace[1639750052] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"146.47203ms","start":"2026-05-19T22:20:03.724376Z","end":"2026-05-19T22:20:03.870848Z","steps":["trace[1639750052] 'range keys from in-memory index tree' (duration: 146.317665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.87087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.306963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.870925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.698663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.870967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.760692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xghhk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-19T22:20:03.871014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.506958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.104968ms","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-19T22:20:03.871041Z","caller":"traceutil/trace.go:171","msg":"trace[1623933428] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"283.57178ms","start":"2026-05-19T22:20:03.587458Z","end":"2026-05-19T22:20:03.87103Z","steps":["trace[1623933428] 'count revisions from in-memory index tree' (duration: 283.355413ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871038Z","caller":"traceutil/trace.go:171","msg":"trace[1514565179] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xghhk; range_end:; response_count:1; response_revision:623; }","duration":"365.835094ms","start":"2026-05-19T22:20:03.505187Z","end":"2026-05-19T22:20:03.871022Z","steps":["trace[1514565179] 'range keys from in-memory index tree' (duration: 365.542426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.062572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.871067Z","caller":"traceutil/trace.go:171","msg":"trace[1669728351] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"163.16478ms","start":"2026-05-19T22:20:03.707884Z","end":"2026-05-19T22:20:03.871049Z","steps":["trace[1669728351] 'range keys from in-memory index tree' (duration: 163.042046ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871089Z","caller":"traceutil/trace.go:171","msg":"trace[109869706] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"365.110873ms","start":"2026-05-19T22:20:03.505968Z","end":"2026-05-19T22:20:03.871079Z","steps":["trace[109869706] 'range keys from in-memory index tree' (duration: 364.959879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.423788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.240921ms","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":"info","ts":"2026-05-19T22:20:03.871151Z","caller":"traceutil/trace.go:171","msg":"trace[1912483978] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"147.294733ms","start":"2026-05-19T22:20:03.723842Z","end":"2026-05-19T22:20:03.871136Z","steps":["trace[1912483978] 'range keys from in-memory index tree' (duration: 147.167819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.505949Z","time spent":"365.156085ms","remote":"127.0.0.1:35166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T22:20:03.871086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.50508Z","time spent":"365.995438ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-xghhk\" "} {"level":"info","ts":"2026-05-19T22:20:03.871172Z","caller":"traceutil/trace.go:171","msg":"trace[571340310] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"186.573532ms","start":"2026-05-19T22:20:03.68456Z","end":"2026-05-19T22:20:03.871133Z","steps":["trace[571340310] 'range keys from in-memory index tree' (duration: 186.328355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.1682ms","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":"info","ts":"2026-05-19T22:20:03.87127Z","caller":"traceutil/trace.go:171","msg":"trace[58260928] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"147.207111ms","start":"2026-05-19T22:20:03.724057Z","end":"2026-05-19T22:20:03.871264Z","steps":["trace[58260928] 'count revisions from in-memory index tree' (duration: 147.120079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.558902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.871319Z","caller":"traceutil/trace.go:171","msg":"trace[1024119967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"186.763898ms","start":"2026-05-19T22:20:03.684541Z","end":"2026-05-19T22:20:03.871304Z","steps":["trace[1024119967] 'count revisions from in-memory index tree' (duration: 186.428518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.87137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.673324ms","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-19T22:20:03.871444Z","caller":"traceutil/trace.go:171","msg":"trace[678435182] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"163.778617ms","start":"2026-05-19T22:20:03.707653Z","end":"2026-05-19T22:20:03.871432Z","steps":["trace[678435182] 'count revisions from in-memory index tree' (duration: 163.582312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.87102Z","caller":"traceutil/trace.go:171","msg":"trace[1043341043] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"339.840947ms","start":"2026-05-19T22:20:03.531162Z","end":"2026-05-19T22:20:03.871003Z","steps":["trace[1043341043] 'count revisions from in-memory index tree' (duration: 339.648991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.343403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.624551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.531154Z","time spent":"340.351261ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:20:03.871533Z","caller":"traceutil/trace.go:171","msg":"trace[1907786806] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"283.401394ms","start":"2026-05-19T22:20:03.588123Z","end":"2026-05-19T22:20:03.871524Z","steps":["trace[1907786806] 'range keys from in-memory index tree' (duration: 283.285701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.395991ms","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":"warn","ts":"2026-05-19T22:20:03.871083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.947095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.871586Z","caller":"traceutil/trace.go:171","msg":"trace[1088229218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"231.446712ms","start":"2026-05-19T22:20:03.640132Z","end":"2026-05-19T22:20:03.871578Z","steps":["trace[1088229218] 'range keys from in-memory index tree' (duration: 231.344529ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871593Z","caller":"traceutil/trace.go:171","msg":"trace[32165177] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"186.48548ms","start":"2026-05-19T22:20:03.685098Z","end":"2026-05-19T22:20:03.871583Z","steps":["trace[32165177] 'range keys from in-memory index tree' (duration: 185.869962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.711855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.870969Z","caller":"traceutil/trace.go:171","msg":"trace[1169359004] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"283.446167ms","start":"2026-05-19T22:20:03.5875Z","end":"2026-05-19T22:20:03.870946Z","steps":["trace[1169359004] 'range keys from in-memory index tree' (duration: 283.120916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.709761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.128893ms","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":"info","ts":"2026-05-19T22:20:03.87167Z","caller":"traceutil/trace.go:171","msg":"trace[1149981330] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"401.849709ms","start":"2026-05-19T22:20:03.469798Z","end":"2026-05-19T22:20:03.871648Z","steps":["trace[1149981330] 'range keys from in-memory index tree' (duration: 401.57195ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.87171Z","caller":"traceutil/trace.go:171","msg":"trace[1778960764] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"231.203065ms","start":"2026-05-19T22:20:03.640497Z","end":"2026-05-19T22:20:03.8717Z","steps":["trace[1778960764] 'range keys from in-memory index tree' (duration: 230.967818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.847968ms","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-19T22:20:03.871721Z","caller":"traceutil/trace.go:171","msg":"trace[1237559817] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"365.02304ms","start":"2026-05-19T22:20:03.506671Z","end":"2026-05-19T22:20:03.871694Z","steps":["trace[1237559817] 'range keys from in-memory index tree' (duration: 364.623068ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871745Z","caller":"traceutil/trace.go:171","msg":"trace[2021581194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"258.90006ms","start":"2026-05-19T22:20:03.612836Z","end":"2026-05-19T22:20:03.871737Z","steps":["trace[2021581194] 'count revisions from in-memory index tree' (duration: 258.775686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.469772Z","time spent":"401.954652ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T22:20:03.871707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.269185ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.246458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.871767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.235206ms","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":"warn","ts":"2026-05-19T22:20:03.871781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.506629Z","time spent":"365.136433ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-05-19T22:20:03.871792Z","caller":"traceutil/trace.go:171","msg":"trace[388080114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:623; }","duration":"391.361627ms","start":"2026-05-19T22:20:03.480421Z","end":"2026-05-19T22:20:03.871783Z","steps":["trace[388080114] 'range keys from in-memory index tree' (duration: 391.251564ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871813Z","caller":"traceutil/trace.go:171","msg":"trace[735780101] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"231.337789ms","start":"2026-05-19T22:20:03.640463Z","end":"2026-05-19T22:20:03.871801Z","steps":["trace[735780101] 'count revisions from in-memory index tree' (duration: 231.03892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.20437ms","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":"info","ts":"2026-05-19T22:20:03.871817Z","caller":"traceutil/trace.go:171","msg":"trace[1011813953] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"340.564868ms","start":"2026-05-19T22:20:03.531233Z","end":"2026-05-19T22:20:03.871798Z","steps":["trace[1011813953] 'range keys from in-memory index tree' (duration: 340.095173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.572762ms","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-05-19T22:20:03.871553Z","caller":"traceutil/trace.go:171","msg":"trace[412969492] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"401.699894ms","start":"2026-05-19T22:20:03.46984Z","end":"2026-05-19T22:20:03.87154Z","steps":["trace[412969492] 'count revisions from in-memory index tree' (duration: 401.527499ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.871844Z","caller":"traceutil/trace.go:171","msg":"trace[1992153551] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"258.244091ms","start":"2026-05-19T22:20:03.613589Z","end":"2026-05-19T22:20:03.871833Z","steps":["trace[1992153551] 'range keys from in-memory index tree' (duration: 258.154498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.531211Z","time spent":"340.636129ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T22:20:03.871869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.469817Z","time spent":"402.042065ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:20:03.871871Z","caller":"traceutil/trace.go:171","msg":"trace[2077983827] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"163.363344ms","start":"2026-05-19T22:20:03.708487Z","end":"2026-05-19T22:20:03.87185Z","steps":["trace[2077983827] 'range keys from in-memory index tree' (duration: 162.4779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.871885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.044684ms","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-19T22:20:03.871909Z","caller":"traceutil/trace.go:171","msg":"trace[1664760870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:623; }","duration":"259.093326ms","start":"2026-05-19T22:20:03.612807Z","end":"2026-05-19T22:20:03.8719Z","steps":["trace[1664760870] 'range keys from in-memory index tree' (duration: 258.924501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.872123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.950089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T22:20:03.872147Z","caller":"traceutil/trace.go:171","msg":"trace[1436860013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"427.99375ms","start":"2026-05-19T22:20:03.444143Z","end":"2026-05-19T22:20:03.872137Z","steps":["trace[1436860013] 'range keys from in-memory index tree' (duration: 427.815145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.872165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.444081Z","time spent":"428.079522ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T22:20:03.872162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.232465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:20:03.872196Z","caller":"traceutil/trace.go:171","msg":"trace[977801042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"421.297247ms","start":"2026-05-19T22:20:03.45089Z","end":"2026-05-19T22:20:03.872187Z","steps":["trace[977801042] 'range keys from in-memory index tree' (duration: 421.084801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.872224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.450872Z","time spent":"421.342519ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T22:20:03.870951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.26624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:20:03.872315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.235347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.872358Z","caller":"traceutil/trace.go:171","msg":"trace[948842800] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"340.69115ms","start":"2026-05-19T22:20:03.53165Z","end":"2026-05-19T22:20:03.872341Z","steps":["trace[948842800] 'range keys from in-memory index tree' (duration: 339.221639ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.872392Z","caller":"traceutil/trace.go:171","msg":"trace[333561973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:623; }","duration":"366.381619ms","start":"2026-05-19T22:20:03.505991Z","end":"2026-05-19T22:20:03.872373Z","steps":["trace[333561973] 'count revisions from in-memory index tree' (duration: 365.113154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.872402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.531641Z","time spent":"340.744221ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-19T22:20:03.872477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:03.505948Z","time spent":"366.513284ms","remote":"127.0.0.1:35166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:20:03.888483Z","caller":"traceutil/trace.go:171","msg":"trace[1774241344] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"143.975517ms","start":"2026-05-19T22:20:03.744477Z","end":"2026-05-19T22:20:03.888453Z","steps":["trace[1774241344] 'process raft request' (duration: 133.940259ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.889172Z","caller":"traceutil/trace.go:171","msg":"trace[503001169] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"138.586602ms","start":"2026-05-19T22:20:03.750239Z","end":"2026-05-19T22:20:03.888826Z","steps":["trace[503001169] 'read index received' (duration: 127.122733ms)","trace[503001169] 'applied index is now lower than readState.Index' (duration: 11.459839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:20:03.890847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.416592ms","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":"info","ts":"2026-05-19T22:20:03.89091Z","caller":"traceutil/trace.go:171","msg":"trace[880454407] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"111.496345ms","start":"2026-05-19T22:20:03.779397Z","end":"2026-05-19T22:20:03.890894Z","steps":["trace[880454407] 'agreement among raft nodes before linearized reading' (duration: 111.398312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.891001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.984938ms","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":"info","ts":"2026-05-19T22:20:03.891034Z","caller":"traceutil/trace.go:171","msg":"trace[810179525] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"112.04825ms","start":"2026-05-19T22:20:03.778977Z","end":"2026-05-19T22:20:03.891025Z","steps":["trace[810179525] 'agreement among raft nodes before linearized reading' (duration: 111.988389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.891052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.725794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:03.891092Z","caller":"traceutil/trace.go:171","msg":"trace[2085483008] 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:626; }","duration":"140.812057ms","start":"2026-05-19T22:20:03.750267Z","end":"2026-05-19T22:20:03.891079Z","steps":["trace[2085483008] 'agreement among raft nodes before linearized reading' (duration: 140.477317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.891177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.42156ms","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":"warn","ts":"2026-05-19T22:20:03.89121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.978151ms","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-05-19T22:20:03.891213Z","caller":"traceutil/trace.go:171","msg":"trace[1451932818] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"112.478932ms","start":"2026-05-19T22:20:03.778725Z","end":"2026-05-19T22:20:03.891204Z","steps":["trace[1451932818] 'agreement among raft nodes before linearized reading' (duration: 112.415781ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:03.891236Z","caller":"traceutil/trace.go:171","msg":"trace[787541981] 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:626; }","duration":"141.026752ms","start":"2026-05-19T22:20:03.750203Z","end":"2026-05-19T22:20:03.891229Z","steps":["trace[787541981] 'agreement among raft nodes before linearized reading' (duration: 139.070606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:03.891304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.289072ms","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-05-19T22:20:03.891538Z","caller":"traceutil/trace.go:171","msg":"trace[104488192] 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:626; }","duration":"140.546178ms","start":"2026-05-19T22:20:03.750977Z","end":"2026-05-19T22:20:03.891523Z","steps":["trace[104488192] 'agreement among raft nodes before linearized reading' (duration: 140.305192ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:06.41638Z","caller":"traceutil/trace.go:171","msg":"trace[930623653] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"103.596108ms","start":"2026-05-19T22:21:06.312761Z","end":"2026-05-19T22:21:06.416358Z","steps":["trace[930623653] 'read index received' (duration: 100.879755ms)","trace[930623653] 'applied index is now lower than readState.Index' (duration: 2.715363ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:06.416469Z","caller":"traceutil/trace.go:171","msg":"trace[1218304326] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"104.136873ms","start":"2026-05-19T22:21:06.3123Z","end":"2026-05-19T22:21:06.416437Z","steps":["trace[1218304326] 'process raft request' (duration: 101.225865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:06.416813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.01099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T22:21:06.41695Z","caller":"traceutil/trace.go:171","msg":"trace[6497565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"104.209045ms","start":"2026-05-19T22:21:06.312714Z","end":"2026-05-19T22:21:06.416923Z","steps":["trace[6497565] 'agreement among raft nodes before linearized reading' (duration: 103.746243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:06.417516Z","caller":"traceutil/trace.go:171","msg":"trace[1087852732] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"101.78576ms","start":"2026-05-19T22:21:06.315444Z","end":"2026-05-19T22:21:06.417229Z","steps":["trace[1087852732] 'process raft request' (duration: 101.0677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:40.081971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.180637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T22:21:40.082082Z","caller":"traceutil/trace.go:171","msg":"trace[12537324] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1878; }","duration":"188.357961ms","start":"2026-05-19T22:21:39.893704Z","end":"2026-05-19T22:21:40.082062Z","steps":["trace[12537324] 'range keys from in-memory index tree' (duration: 187.94002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:40.082384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.551231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-272e3ec5-7a83-4ef2-a6d3-a09cf54bed0a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T22:21:40.082492Z","caller":"traceutil/trace.go:171","msg":"trace[1641467462] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-272e3ec5-7a83-4ef2-a6d3-a09cf54bed0a; range_end:; response_count:1; response_revision:1878; }","duration":"221.740597ms","start":"2026-05-19T22:21:39.860726Z","end":"2026-05-19T22:21:40.082467Z","steps":["trace[1641467462] 'range keys from in-memory index tree' (duration: 221.017068ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:53.422183Z","caller":"traceutil/trace.go:171","msg":"trace[1108709760] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"106.190129ms","start":"2026-05-19T22:21:53.315961Z","end":"2026-05-19T22:21:53.422152Z","steps":["trace[1108709760] 'read index received' (duration: 102.493229ms)","trace[1108709760] 'applied index is now lower than readState.Index' (duration: 3.695529ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:53.422371Z","caller":"traceutil/trace.go:171","msg":"trace[852495132] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"193.16476ms","start":"2026-05-19T22:21:53.229192Z","end":"2026-05-19T22:21:53.422357Z","steps":["trace[852495132] 'process raft request' (duration: 189.371238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:53.422938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T22:21:53.423047Z","caller":"traceutil/trace.go:171","msg":"trace[1329985005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1992; }","duration":"107.082011ms","start":"2026-05-19T22:21:53.315938Z","end":"2026-05-19T22:21:53.42302Z","steps":["trace[1329985005] 'agreement among raft nodes before linearized reading' (duration: 106.803494ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:25.257729Z","caller":"traceutil/trace.go:171","msg":"trace[1167883406] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"122.365022ms","start":"2026-05-19T22:22:25.135322Z","end":"2026-05-19T22:22:25.257687Z","steps":["trace[1167883406] 'process raft request' (duration: 118.229261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:31.159808Z","caller":"traceutil/trace.go:171","msg":"trace[1349098519] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"225.421416ms","start":"2026-05-19T22:22:30.934358Z","end":"2026-05-19T22:22:31.15978Z","steps":["trace[1349098519] 'process raft request' (duration: 225.343234ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:31.159877Z","caller":"traceutil/trace.go:171","msg":"trace[325865857] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"225.488977ms","start":"2026-05-19T22:22:30.934334Z","end":"2026-05-19T22:22:31.159823Z","steps":["trace[325865857] 'process raft request' (duration: 208.455201ms)","trace[325865857] 'compare' (duration: 16.739529ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:22:31.711117Z","caller":"traceutil/trace.go:171","msg":"trace[1804952499] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"247.811887ms","start":"2026-05-19T22:22:31.463247Z","end":"2026-05-19T22:22:31.711059Z","steps":["trace[1804952499] 'process raft request' (duration: 154.72255ms)","trace[1804952499] 'compare' (duration: 92.797448ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:22.630213Z","caller":"traceutil/trace.go:171","msg":"trace[719092231] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"100.095266ms","start":"2026-05-19T22:23:22.530085Z","end":"2026-05-19T22:23:22.63018Z","steps":["trace[719092231] 'process raft request' (duration: 99.87599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:57.744296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.717251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:23:57.744426Z","caller":"traceutil/trace.go:171","msg":"trace[923946062] linearizableReadLoop","detail":"{readStateIndex:2981; appliedIndex:2980; }","duration":"252.46414ms","start":"2026-05-19T22:23:57.491943Z","end":"2026-05-19T22:23:57.744407Z","steps":["trace[923946062] 'read index received' (duration: 113.410178ms)","trace[923946062] 'applied index is now lower than readState.Index' (duration: 139.052782ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:57.744503Z","caller":"traceutil/trace.go:171","msg":"trace[230514121] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"267.005946ms","start":"2026-05-19T22:23:57.477485Z","end":"2026-05-19T22:23:57.744491Z","steps":["trace[230514121] 'process raft request' (duration: 127.850711ms)","trace[230514121] 'compare' (duration: 138.511086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:57.744559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.608474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-19T22:23:57.744639Z","caller":"traceutil/trace.go:171","msg":"trace[1261660494] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2869; }","duration":"252.716587ms","start":"2026-05-19T22:23:57.491908Z","end":"2026-05-19T22:23:57.744624Z","steps":["trace[1261660494] 'agreement among raft nodes before linearized reading' (duration: 252.576493ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:57.853838Z","caller":"traceutil/trace.go:171","msg":"trace[1394623117] linearizableReadLoop","detail":"{readStateIndex:2982; appliedIndex:2981; }","duration":"109.348481ms","start":"2026-05-19T22:23:57.744455Z","end":"2026-05-19T22:23:57.853803Z","steps":["trace[1394623117] 'read index received' (duration: 101.226778ms)","trace[1394623117] 'applied index is now lower than readState.Index' (duration: 8.119993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:57.854112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.398924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:23:57.854102Z","caller":"traceutil/trace.go:171","msg":"trace[952637328] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"340.967902ms","start":"2026-05-19T22:23:57.513074Z","end":"2026-05-19T22:23:57.854042Z","steps":["trace[952637328] 'process raft request' (duration: 332.648254ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:57.854203Z","caller":"traceutil/trace.go:171","msg":"trace[1719892753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2870; }","duration":"151.552368ms","start":"2026-05-19T22:23:57.70263Z","end":"2026-05-19T22:23:57.854183Z","steps":["trace[1719892753] 'agreement among raft nodes before linearized reading' (duration: 151.222019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:57.854114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.297203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-19T22:23:57.854351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.978992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T22:23:57.85437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:57.513044Z","time spent":"341.178129ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:23:57.854397Z","caller":"traceutil/trace.go:171","msg":"trace[335109330] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2870; }","duration":"108.022483ms","start":"2026-05-19T22:23:57.746359Z","end":"2026-05-19T22:23:57.854381Z","steps":["trace[335109330] 'agreement among raft nodes before linearized reading' (duration: 107.829738ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:57.8544Z","caller":"traceutil/trace.go:171","msg":"trace[1294924621] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2870; }","duration":"230.599012ms","start":"2026-05-19T22:23:57.623778Z","end":"2026-05-19T22:23:57.854377Z","steps":["trace[1294924621] 'agreement among raft nodes before linearized reading' (duration: 230.16145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:57.854135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.708037ms","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-05-19T22:23:57.854603Z","caller":"traceutil/trace.go:171","msg":"trace[386032094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2870; }","duration":"252.259703ms","start":"2026-05-19T22:23:57.602329Z","end":"2026-05-19T22:23:57.854589Z","steps":["trace[386032094] 'agreement among raft nodes before linearized reading' (duration: 251.669276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:57.854136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.071465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:23:57.854711Z","caller":"traceutil/trace.go:171","msg":"trace[1494928222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2870; }","duration":"225.732683ms","start":"2026-05-19T22:23:57.628969Z","end":"2026-05-19T22:23:57.854701Z","steps":["trace[1494928222] 'agreement among raft nodes before linearized reading' (duration: 224.91122ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:00.461889Z","caller":"traceutil/trace.go:171","msg":"trace[249415856] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5137; }","duration":"121.098329ms","start":"2026-05-19T22:28:00.340765Z","end":"2026-05-19T22:28:00.461864Z","steps":["trace[249415856] 'read index received' (duration: 10.423956ms)","trace[249415856] 'applied index is now lower than readState.Index' (duration: 110.673293ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:28:00.461944Z","caller":"traceutil/trace.go:171","msg":"trace[1654873545] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"126.389359ms","start":"2026-05-19T22:28:00.335517Z","end":"2026-05-19T22:28:00.461906Z","steps":["trace[1654873545] 'process raft request' (duration: 108.595414ms)","trace[1654873545] 'compare' (duration: 17.62425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:28:00.462114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.327616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-05-19T22:28:00.462155Z","caller":"traceutil/trace.go:171","msg":"trace[1659280799] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4948; }","duration":"121.384788ms","start":"2026-05-19T22:28:00.340759Z","end":"2026-05-19T22:28:00.462144Z","steps":["trace[1659280799] 'agreement among raft nodes before linearized reading' (duration: 121.221863ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:00.487145Z","caller":"traceutil/trace.go:171","msg":"trace[2121769485] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4949; }","duration":"114.323096ms","start":"2026-05-19T22:28:00.372797Z","end":"2026-05-19T22:28:00.48712Z","steps":["trace[2121769485] 'process raft request' (duration: 114.234123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:00.48724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.94548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-19T22:28:00.4873Z","caller":"traceutil/trace.go:171","msg":"trace[663674329] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4950; }","duration":"130.055533ms","start":"2026-05-19T22:28:00.35723Z","end":"2026-05-19T22:28:00.487286Z","steps":["trace[663674329] 'agreement among raft nodes before linearized reading' (duration: 129.880358ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:00.48745Z","caller":"traceutil/trace.go:171","msg":"trace[1200414428] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"114.77721ms","start":"2026-05-19T22:28:00.37266Z","end":"2026-05-19T22:28:00.487437Z","steps":["trace[1200414428] 'process raft request' (duration: 114.269854ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:00.613103Z","caller":"traceutil/trace.go:171","msg":"trace[961597650] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"117.247039ms","start":"2026-05-19T22:28:00.495818Z","end":"2026-05-19T22:28:00.613065Z","steps":["trace[961597650] 'process raft request' (duration: 117.001832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:00.61355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.73119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-2vlsd-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-05-19T22:28:00.613691Z","caller":"traceutil/trace.go:171","msg":"trace[1903926175] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-2vlsd-tls; range_end:; response_count:1; response_revision:4952; }","duration":"113.906476ms","start":"2026-05-19T22:28:00.499725Z","end":"2026-05-19T22:28:00.613631Z","steps":["trace[1903926175] 'agreement among raft nodes before linearized reading' (duration: 113.516344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:00.613945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.265443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7f5d2069-14dd-4d6f-981a-68661e5eae07\" ","response":"range_response_count:1 size:1293"} {"level":"warn","ts":"2026-05-19T22:28:00.613959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.851272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2199611"} {"level":"info","ts":"2026-05-19T22:28:00.614031Z","caller":"traceutil/trace.go:171","msg":"trace[232598785] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4952; }","duration":"108.968235ms","start":"2026-05-19T22:28:00.505047Z","end":"2026-05-19T22:28:00.614016Z","steps":["trace[232598785] 'agreement among raft nodes before linearized reading' (duration: 108.09767ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:00.614025Z","caller":"traceutil/trace.go:171","msg":"trace[862545272] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7f5d2069-14dd-4d6f-981a-68661e5eae07; range_end:; response_count:1; response_revision:4952; }","duration":"122.386396ms","start":"2026-05-19T22:28:00.491617Z","end":"2026-05-19T22:28:00.614004Z","steps":["trace[862545272] 'agreement among raft nodes before linearized reading' (duration: 122.099738ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:04.881427Z","caller":"traceutil/trace.go:171","msg":"trace[1114879257] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"118.797434ms","start":"2026-05-19T22:28:04.762582Z","end":"2026-05-19T22:28:04.88138Z","steps":["trace[1114879257] 'process raft request' (duration: 118.570638ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:17.413103Z","caller":"traceutil/trace.go:171","msg":"trace[105028853] linearizableReadLoop","detail":"{readStateIndex:5532; appliedIndex:5531; }","duration":"385.915709ms","start":"2026-05-19T22:28:17.027164Z","end":"2026-05-19T22:28:17.413079Z","steps":["trace[105028853] 'read index received' (duration: 385.714164ms)","trace[105028853] 'applied index is now lower than readState.Index' (duration: 200.595µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:28:17.413222Z","caller":"traceutil/trace.go:171","msg":"trace[1015130178] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"396.219032ms","start":"2026-05-19T22:28:17.016977Z","end":"2026-05-19T22:28:17.413196Z","steps":["trace[1015130178] 'process raft request' (duration: 395.958685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:17.413342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:28:17.016955Z","time spent":"396.323226ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:28:17.413347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.57098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-19T22:28:17.413424Z","caller":"traceutil/trace.go:171","msg":"trace[1719534178] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5330; }","duration":"391.680564ms","start":"2026-05-19T22:28:17.021721Z","end":"2026-05-19T22:28:17.413402Z","steps":["trace[1719534178] 'agreement among raft nodes before linearized reading' (duration: 391.469767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:17.413465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.708854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-6gcd5\" ","response":"range_response_count:1 size:7818"} {"level":"warn","ts":"2026-05-19T22:28:17.413483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:28:17.021709Z","time spent":"391.759916ms","remote":"127.0.0.1:37284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-19T22:28:17.413483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.380174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T22:28:17.413488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.651044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:28:17.413571Z","caller":"traceutil/trace.go:171","msg":"trace[1041882038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5330; }","duration":"174.489746ms","start":"2026-05-19T22:28:17.239058Z","end":"2026-05-19T22:28:17.413548Z","steps":["trace[1041882038] 'agreement among raft nodes before linearized reading' (duration: 174.271369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:17.413573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.254784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T22:28:17.413598Z","caller":"traceutil/trace.go:171","msg":"trace[1021543932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5330; }","duration":"172.813738ms","start":"2026-05-19T22:28:17.240765Z","end":"2026-05-19T22:28:17.413579Z","steps":["trace[1021543932] 'agreement among raft nodes before linearized reading' (duration: 172.504949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:17.41365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.166591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wgnc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-19T22:28:17.413647Z","caller":"traceutil/trace.go:171","msg":"trace[1485494738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5330; }","duration":"196.351887ms","start":"2026-05-19T22:28:17.217274Z","end":"2026-05-19T22:28:17.413626Z","steps":["trace[1485494738] 'agreement among raft nodes before linearized reading' (duration: 196.145331ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:17.413532Z","caller":"traceutil/trace.go:171","msg":"trace[932807000] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-6gcd5; range_end:; response_count:1; response_revision:5330; }","duration":"104.819497ms","start":"2026-05-19T22:28:17.308696Z","end":"2026-05-19T22:28:17.413515Z","steps":["trace[932807000] 'agreement among raft nodes before linearized reading' (duration: 104.669412ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:17.414048Z","caller":"traceutil/trace.go:171","msg":"trace[330558053] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wgnc; range_end:; response_count:1; response_revision:5330; }","duration":"378.226022ms","start":"2026-05-19T22:28:17.035446Z","end":"2026-05-19T22:28:17.413672Z","steps":["trace[330558053] 'agreement among raft nodes before linearized reading' (duration: 377.776948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:17.414097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:28:17.035435Z","time spent":"378.651025ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-5wgnc\" "} {"level":"info","ts":"2026-05-19T22:28:25.163577Z","caller":"traceutil/trace.go:171","msg":"trace[1742424049] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"103.337034ms","start":"2026-05-19T22:28:25.060218Z","end":"2026-05-19T22:28:25.163555Z","steps":["trace[1742424049] 'process raft request' (duration: 103.218641ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:29:17.277742Z","caller":"traceutil/trace.go:171","msg":"trace[420120170] transaction","detail":"{read_only:false; response_revision:6002; number_of_response:1; }","duration":"100.140224ms","start":"2026-05-19T22:29:17.177554Z","end":"2026-05-19T22:29:17.277694Z","steps":["trace[420120170] 'process raft request' (duration: 99.915168ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:29:23.982425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-05-19T22:29:24.077076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"93.024452ms","hash":833627471,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T22:29:24.077168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":833627471,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-05-19T22:34:23.989673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6070} {"level":"info","ts":"2026-05-19T22:34:24.092266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6070,"took":"100.715348ms","hash":2890365240,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":21762048,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-19T22:34:24.092339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2890365240,"revision":6070,"compact-revision":3012} {"level":"info","ts":"2026-05-19T22:34:41.460252Z","caller":"traceutil/trace.go:171","msg":"trace[1941598844] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"207.685554ms","start":"2026-05-19T22:34:41.252525Z","end":"2026-05-19T22:34:41.460211Z","steps":["trace[1941598844] 'process raft request' (duration: 207.497119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:41.61529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.344373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:34:41.615362Z","caller":"traceutil/trace.go:171","msg":"trace[1258651463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8386; }","duration":"108.451727ms","start":"2026-05-19T22:34:41.506895Z","end":"2026-05-19T22:34:41.615347Z","steps":["trace[1258651463] 'range keys from in-memory index tree' (duration: 108.165519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:41.61534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.321944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T22:34:41.615407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.905824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:34:41.615498Z","caller":"traceutil/trace.go:171","msg":"trace[1874258820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8386; }","duration":"106.07948ms","start":"2026-05-19T22:34:41.5094Z","end":"2026-05-19T22:34:41.615479Z","steps":["trace[1874258820] 'range keys from in-memory index tree' (duration: 105.650167ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:41.615439Z","caller":"traceutil/trace.go:171","msg":"trace[858604961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8386; }","duration":"120.474799ms","start":"2026-05-19T22:34:41.494949Z","end":"2026-05-19T22:34:41.615424Z","steps":["trace[858604961] 'range keys from in-memory index tree' (duration: 120.120718ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:53.760467Z","caller":"traceutil/trace.go:171","msg":"trace[527795863] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"260.193294ms","start":"2026-05-19T22:34:53.500235Z","end":"2026-05-19T22:34:53.760429Z","steps":["trace[527795863] 'process raft request' (duration: 259.886996ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:22.902866Z","caller":"traceutil/trace.go:171","msg":"trace[2018694340] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"200.750287ms","start":"2026-05-19T22:35:22.702095Z","end":"2026-05-19T22:35:22.902846Z","steps":["trace[2018694340] 'process raft request' (duration: 200.643494ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:33.902556Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002369.wal"} {"level":"info","ts":"2026-05-19T22:35:35.394631Z","caller":"traceutil/trace.go:171","msg":"trace[410389921] linearizableReadLoop","detail":"{readStateIndex:9083; appliedIndex:9082; }","duration":"133.144949ms","start":"2026-05-19T22:35:35.261465Z","end":"2026-05-19T22:35:35.39461Z","steps":["trace[410389921] 'read index received' (duration: 133.062067ms)","trace[410389921] 'applied index is now lower than readState.Index' (duration: 82.052µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:35:35.394724Z","caller":"traceutil/trace.go:171","msg":"trace[1085355664] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"135.738152ms","start":"2026-05-19T22:35:35.258948Z","end":"2026-05-19T22:35:35.394686Z","steps":["trace[1085355664] 'process raft request' (duration: 135.547467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:35.394809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.328064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ntm87\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-19T22:35:35.39485Z","caller":"traceutil/trace.go:171","msg":"trace[1749016579] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ntm87; range_end:; response_count:1; response_revision:8765; }","duration":"133.412856ms","start":"2026-05-19T22:35:35.261428Z","end":"2026-05-19T22:35:35.394841Z","steps":["trace[1749016579] 'agreement among raft nodes before linearized reading' (duration: 133.281393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:35.649613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.580726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:35:35.649729Z","caller":"traceutil/trace.go:171","msg":"trace[102244292] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9083; }","duration":"255.020167ms","start":"2026-05-19T22:35:35.394693Z","end":"2026-05-19T22:35:35.649713Z","steps":["trace[102244292] 'read index received' (duration: 124.41321ms)","trace[102244292] 'applied index is now lower than readState.Index' (duration: 130.605927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:35:35.64982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.89623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:35:35.649853Z","caller":"traceutil/trace.go:171","msg":"trace[1948848961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8766; }","duration":"299.970882ms","start":"2026-05-19T22:35:35.349874Z","end":"2026-05-19T22:35:35.649845Z","steps":["trace[1948848961] 'agreement among raft nodes before linearized reading' (duration: 299.884589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:35.649893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:35:35.349853Z","time spent":"300.033694ms","remote":"127.0.0.1:37056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T22:35:35.649944Z","caller":"traceutil/trace.go:171","msg":"trace[1455985184] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"387.98021ms","start":"2026-05-19T22:35:35.261877Z","end":"2026-05-19T22:35:35.649857Z","steps":["trace[1455985184] 'process raft request' (duration: 257.088106ms)","trace[1455985184] 'compare' (duration: 130.459442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:35:35.650093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:35:35.261856Z","time spent":"388.168136ms","remote":"127.0.0.1:37160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:35:35.697434Z","caller":"traceutil/trace.go:171","msg":"trace[730318507] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"294.286031ms","start":"2026-05-19T22:35:35.403123Z","end":"2026-05-19T22:35:35.697409Z","steps":["trace[730318507] 'process raft request' (duration: 294.146617ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:35.719986Z","caller":"traceutil/trace.go:171","msg":"trace[1114396285] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"141.426364ms","start":"2026-05-19T22:35:35.578534Z","end":"2026-05-19T22:35:35.71996Z","steps":["trace[1114396285] 'process raft request' (duration: 140.92867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:36.145137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.921475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:35:36.145238Z","caller":"traceutil/trace.go:171","msg":"trace[420544137] linearizableReadLoop","detail":"{readStateIndex:9090; appliedIndex:9089; }","duration":"175.84315ms","start":"2026-05-19T22:35:35.969382Z","end":"2026-05-19T22:35:36.145225Z","steps":["trace[420544137] 'read index received' (duration: 32.697989ms)","trace[420544137] 'applied index is now lower than readState.Index' (duration: 143.144291ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:35:36.145326Z","caller":"traceutil/trace.go:171","msg":"trace[847937946] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"229.881244ms","start":"2026-05-19T22:35:35.915436Z","end":"2026-05-19T22:35:36.145317Z","steps":["trace[847937946] 'process raft request' (duration: 86.689031ms)","trace[847937946] 'compare' (duration: 142.665958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:35:36.145775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.380255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-19T22:35:36.145857Z","caller":"traceutil/trace.go:171","msg":"trace[788207847] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8772; }","duration":"176.51389ms","start":"2026-05-19T22:35:35.969324Z","end":"2026-05-19T22:35:36.145838Z","steps":["trace[788207847] 'agreement among raft nodes before linearized reading' (duration: 176.275433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:36.145874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.873011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T22:35:36.145978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.464091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T22:35:36.146007Z","caller":"traceutil/trace.go:171","msg":"trace[257876200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8772; }","duration":"100.526643ms","start":"2026-05-19T22:35:36.045471Z","end":"2026-05-19T22:35:36.145998Z","steps":["trace[257876200] 'agreement among raft nodes before linearized reading' (duration: 100.446101ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:36.146072Z","caller":"traceutil/trace.go:171","msg":"trace[482714131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8772; }","duration":"102.030435ms","start":"2026-05-19T22:35:36.043956Z","end":"2026-05-19T22:35:36.145986Z","steps":["trace[482714131] 'agreement among raft nodes before linearized reading' (duration: 101.766677ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:36.289738Z","caller":"traceutil/trace.go:171","msg":"trace[723974757] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"139.133888ms","start":"2026-05-19T22:35:36.150578Z","end":"2026-05-19T22:35:36.289712Z","steps":["trace[723974757] 'process raft request' (duration: 139.059396ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:36.289789Z","caller":"traceutil/trace.go:171","msg":"trace[2032336851] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"139.321603ms","start":"2026-05-19T22:35:36.150432Z","end":"2026-05-19T22:35:36.289754Z","steps":["trace[2032336851] 'process raft request' (duration: 108.539869ms)","trace[2032336851] 'compare' (duration: 30.514887ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:35:36.290008Z","caller":"traceutil/trace.go:171","msg":"trace[450010261] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"139.088617ms","start":"2026-05-19T22:35:36.150896Z","end":"2026-05-19T22:35:36.289984Z","steps":["trace[450010261] 'process raft request' (duration: 138.782278ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:36.290141Z","caller":"traceutil/trace.go:171","msg":"trace[1447812503] linearizableReadLoop","detail":"{readStateIndex:9095; appliedIndex:9090; }","duration":"116.369243ms","start":"2026-05-19T22:35:36.173763Z","end":"2026-05-19T22:35:36.290133Z","steps":["trace[1447812503] 'read index received' (duration: 1.4385ms)","trace[1447812503] 'applied index is now lower than readState.Index' (duration: 114.929733ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:35:36.290173Z","caller":"traceutil/trace.go:171","msg":"trace[890574299] transaction","detail":"{read_only:false; response_revision:8777; number_of_response:1; }","duration":"137.485892ms","start":"2026-05-19T22:35:36.152659Z","end":"2026-05-19T22:35:36.290145Z","steps":["trace[890574299] 'process raft request' (duration: 137.240524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:35:36.290441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.656841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wgnc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-19T22:35:36.290475Z","caller":"traceutil/trace.go:171","msg":"trace[1469567006] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wgnc; range_end:; response_count:1; response_revision:8777; }","duration":"116.734923ms","start":"2026-05-19T22:35:36.173732Z","end":"2026-05-19T22:35:36.290467Z","steps":["trace[1469567006] 'agreement among raft nodes before linearized reading' (duration: 116.477796ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:36:56.749181Z","caller":"traceutil/trace.go:171","msg":"trace[1137037198] transaction","detail":"{read_only:false; response_revision:9542; number_of_response:1; }","duration":"148.660768ms","start":"2026-05-19T22:36:56.60049Z","end":"2026-05-19T22:36:56.749151Z","steps":["trace[1137037198] 'process raft request' (duration: 147.864774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:36:56.949318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.871471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:36:56.949664Z","caller":"traceutil/trace.go:171","msg":"trace[1666600353] transaction","detail":"{read_only:false; response_revision:9543; number_of_response:1; }","duration":"196.402369ms","start":"2026-05-19T22:36:56.753225Z","end":"2026-05-19T22:36:56.949628Z","steps":["trace[1666600353] 'process raft request' (duration: 87.111656ms)","trace[1666600353] 'compare' (duration: 108.757238ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:36:56.950276Z","caller":"traceutil/trace.go:171","msg":"trace[296878828] transaction","detail":"{read_only:false; response_revision:9544; number_of_response:1; }","duration":"196.753489ms","start":"2026-05-19T22:36:56.753454Z","end":"2026-05-19T22:36:56.950207Z","steps":["trace[296878828] 'process raft request' (duration: 196.299326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:36:56.950673Z","caller":"traceutil/trace.go:171","msg":"trace[192941011] transaction","detail":"{read_only:false; response_revision:9545; number_of_response:1; }","duration":"197.1407ms","start":"2026-05-19T22:36:56.753521Z","end":"2026-05-19T22:36:56.950661Z","steps":["trace[192941011] 'process raft request' (duration: 196.505021ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:36:56.950763Z","caller":"traceutil/trace.go:171","msg":"trace[1345994632] transaction","detail":"{read_only:false; response_revision:9546; number_of_response:1; }","duration":"197.14157ms","start":"2026-05-19T22:36:56.753562Z","end":"2026-05-19T22:36:56.950703Z","steps":["trace[1345994632] 'process raft request' (duration: 196.80714ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:36:56.951074Z","caller":"traceutil/trace.go:171","msg":"trace[1835574636] linearizableReadLoop","detail":"{readStateIndex:9885; appliedIndex:9881; }","duration":"143.654797ms","start":"2026-05-19T22:36:56.80688Z","end":"2026-05-19T22:36:56.950535Z","steps":["trace[1835574636] 'read index received' (duration: 33.440646ms)","trace[1835574636] 'applied index is now lower than readState.Index' (duration: 110.213181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:36:56.951078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.187552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wgnc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-19T22:36:56.951146Z","caller":"traceutil/trace.go:171","msg":"trace[348555939] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wgnc; range_end:; response_count:1; response_revision:9546; }","duration":"144.304235ms","start":"2026-05-19T22:36:56.806826Z","end":"2026-05-19T22:36:56.95113Z","steps":["trace[348555939] 'agreement among raft nodes before linearized reading' (duration: 144.002047ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:37:09.812553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T22:37:09.821363Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T22:37:09.821668Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T22:39:24.000582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8151} {"level":"info","ts":"2026-05-19T22:39:24.07353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8151,"took":"69.637373ms","hash":2195522701,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":28250112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T22:39:24.073632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2195522701,"revision":8151,"compact-revision":6070} {"level":"warn","ts":"2026-05-19T22:39:30.208528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970595940044647,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T22:39:30.709018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970595940044647,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T22:39:30.788163Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211667457s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T22:39:30.788762Z","caller":"traceutil/trace.go:171","msg":"trace[1123434317] transaction","detail":"{read_only:false; response_revision:10548; number_of_response:1; }","duration":"1.21245694s","start":"2026-05-19T22:39:29.576276Z","end":"2026-05-19T22:39:30.788733Z","steps":["trace[1123434317] 'process raft request' (duration: 1.212280574s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.789016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.576239Z","time spent":"1.212694766s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:39:30.789822Z","caller":"traceutil/trace.go:171","msg":"trace[752171736] transaction","detail":"{read_only:false; response_revision:10551; number_of_response:1; }","duration":"430.445878ms","start":"2026-05-19T22:39:30.359351Z","end":"2026-05-19T22:39:30.789797Z","steps":["trace[752171736] 'process raft request' (duration: 430.372966ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:30.789836Z","caller":"traceutil/trace.go:171","msg":"trace[1361381157] transaction","detail":"{read_only:false; response_revision:10549; number_of_response:1; }","duration":"838.117424ms","start":"2026-05-19T22:39:29.951677Z","end":"2026-05-19T22:39:30.789795Z","steps":["trace[1361381157] 'process raft request' (duration: 837.870927ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:30.789869Z","caller":"traceutil/trace.go:171","msg":"trace[1508177367] transaction","detail":"{read_only:false; response_revision:10550; number_of_response:1; }","duration":"770.307572ms","start":"2026-05-19T22:39:30.019513Z","end":"2026-05-19T22:39:30.78982Z","steps":["trace[1508177367] 'process raft request' (duration: 770.175598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.789975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.359317Z","time spent":"430.578022ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:39:30.790082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.019484Z","time spent":"770.496937ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:39:30.790181Z","caller":"traceutil/trace.go:171","msg":"trace[29812931] transaction","detail":"{read_only:false; response_revision:10552; number_of_response:1; }","duration":"140.380669ms","start":"2026-05-19T22:39:30.649748Z","end":"2026-05-19T22:39:30.790128Z","steps":["trace[29812931] 'process raft request' (duration: 140.009938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.790077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.951646Z","time spent":"838.273209ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:39:30.790643Z","caller":"traceutil/trace.go:171","msg":"trace[1069744014] linearizableReadLoop","detail":"{readStateIndex:10927; appliedIndex:10922; }","duration":"1.082657459s","start":"2026-05-19T22:39:29.707961Z","end":"2026-05-19T22:39:30.790618Z","steps":["trace[1069744014] 'read index received' (duration: 1.080538589s)","trace[1069744014] 'applied index is now lower than readState.Index' (duration: 2.1169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:39:30.790833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082863445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T22:39:30.790908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.227933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:39:30.790995Z","caller":"traceutil/trace.go:171","msg":"trace[2091046195] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:10552; }","duration":"146.362527ms","start":"2026-05-19T22:39:30.64461Z","end":"2026-05-19T22:39:30.790972Z","steps":["trace[2091046195] 'agreement among raft nodes before linearized reading' (duration: 146.207613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.791053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.802876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:39:30.790907Z","caller":"traceutil/trace.go:171","msg":"trace[1455158594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10552; }","duration":"1.082976058s","start":"2026-05-19T22:39:29.707907Z","end":"2026-05-19T22:39:30.790883Z","steps":["trace[1455158594] 'agreement among raft nodes before linearized reading' (duration: 1.082793023s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.791081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057125869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T22:39:30.791158Z","caller":"traceutil/trace.go:171","msg":"trace[1440430111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10552; }","duration":"1.057221032s","start":"2026-05-19T22:39:29.73391Z","end":"2026-05-19T22:39:30.791131Z","steps":["trace[1440430111] 'agreement among raft nodes before linearized reading' (duration: 1.056932994s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.79125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.733893Z","time spent":"1.057343605s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T22:39:30.791137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.707867Z","time spent":"1.083252406s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T22:39:30.791283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08236011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T22:39:30.791365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082061362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:39:30.791615Z","caller":"traceutil/trace.go:171","msg":"trace[180066486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10552; }","duration":"1.08231757s","start":"2026-05-19T22:39:29.709278Z","end":"2026-05-19T22:39:30.791595Z","steps":["trace[180066486] 'agreement among raft nodes before linearized reading' (duration: 1.081911608s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.791681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.709273Z","time spent":"1.082387472s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T22:39:30.791382Z","caller":"traceutil/trace.go:171","msg":"trace[1049812437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10552; }","duration":"1.082632049s","start":"2026-05-19T22:39:29.708726Z","end":"2026-05-19T22:39:30.791358Z","steps":["trace[1049812437] 'agreement among raft nodes before linearized reading' (duration: 1.082050783s)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:30.791138Z","caller":"traceutil/trace.go:171","msg":"trace[1133070350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10552; }","duration":"626.101974ms","start":"2026-05-19T22:39:30.164997Z","end":"2026-05-19T22:39:30.791099Z","steps":["trace[1133070350] 'agreement among raft nodes before linearized reading' (duration: 625.783656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.791818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.70872Z","time spent":"1.083051811s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T22:39:30.791426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.615341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:39:30.792207Z","caller":"traceutil/trace.go:171","msg":"trace[2123204236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10552; }","duration":"398.427315ms","start":"2026-05-19T22:39:30.393747Z","end":"2026-05-19T22:39:30.792174Z","steps":["trace[2123204236] 'agreement among raft nodes before linearized reading' (duration: 397.635112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.792255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.393714Z","time spent":"398.528849ms","remote":"127.0.0.1:37056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T22:39:30.791371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.699677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wgnc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-19T22:39:30.79234Z","caller":"traceutil/trace.go:171","msg":"trace[681975760] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wgnc; range_end:; response_count:1; response_revision:10552; }","duration":"796.702336ms","start":"2026-05-19T22:39:29.995618Z","end":"2026-05-19T22:39:30.79232Z","steps":["trace[681975760] 'agreement among raft nodes before linearized reading' (duration: 795.538193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.792406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.995604Z","time spent":"796.784019ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-5wgnc\" "} {"level":"warn","ts":"2026-05-19T22:39:30.792113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.164983Z","time spent":"626.891458ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:42:29.597668Z","caller":"traceutil/trace.go:171","msg":"trace[1935393823] transaction","detail":"{read_only:false; response_revision:11937; number_of_response:1; }","duration":"122.355399ms","start":"2026-05-19T22:42:29.475267Z","end":"2026-05-19T22:42:29.597622Z","steps":["trace[1935393823] 'process raft request' (duration: 122.204245ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:42:29.658527Z","caller":"traceutil/trace.go:171","msg":"trace[927156592] transaction","detail":"{read_only:false; response_revision:11940; number_of_response:1; }","duration":"149.450112ms","start":"2026-05-19T22:42:29.509059Z","end":"2026-05-19T22:42:29.658509Z","steps":["trace[927156592] 'process raft request' (duration: 149.404501ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:42:29.658522Z","caller":"traceutil/trace.go:171","msg":"trace[2004027702] transaction","detail":"{read_only:false; response_revision:11938; number_of_response:1; }","duration":"160.830175ms","start":"2026-05-19T22:42:29.497649Z","end":"2026-05-19T22:42:29.65848Z","steps":["trace[2004027702] 'process raft request' (duration: 155.946187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:42:29.658607Z","caller":"traceutil/trace.go:171","msg":"trace[1014417204] transaction","detail":"{read_only:false; response_revision:11939; number_of_response:1; }","duration":"160.00491ms","start":"2026-05-19T22:42:29.498592Z","end":"2026-05-19T22:42:29.658597Z","steps":["trace[1014417204] 'process raft request' (duration: 159.808404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:44:24.007433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10522} {"level":"info","ts":"2026-05-19T22:44:24.087617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10522,"took":"76.63314ms","hash":2016837716,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":26390528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T22:44:24.087812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2016837716,"revision":10522,"compact-revision":8151} {"level":"info","ts":"2026-05-19T22:48:41.180598Z","caller":"traceutil/trace.go:171","msg":"trace[1054845875] transaction","detail":"{read_only:false; response_revision:14441; number_of_response:1; }","duration":"162.874153ms","start":"2026-05-19T22:48:41.017699Z","end":"2026-05-19T22:48:41.180573Z","steps":["trace[1054845875] 'process raft request' (duration: 162.730449ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:48:41.182666Z","caller":"traceutil/trace.go:171","msg":"trace[529701689] transaction","detail":"{read_only:false; response_revision:14444; number_of_response:1; }","duration":"139.930265ms","start":"2026-05-19T22:48:41.04271Z","end":"2026-05-19T22:48:41.182641Z","steps":["trace[529701689] 'process raft request' (duration: 139.884444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:48:41.183124Z","caller":"traceutil/trace.go:171","msg":"trace[1775703763] transaction","detail":"{read_only:false; response_revision:14442; number_of_response:1; }","duration":"145.156732ms","start":"2026-05-19T22:48:41.037951Z","end":"2026-05-19T22:48:41.183108Z","steps":["trace[1775703763] 'process raft request' (duration: 144.462222ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:48:41.183319Z","caller":"traceutil/trace.go:171","msg":"trace[2145758539] transaction","detail":"{read_only:false; response_revision:14443; number_of_response:1; }","duration":"144.119764ms","start":"2026-05-19T22:48:41.039188Z","end":"2026-05-19T22:48:41.183308Z","steps":["trace[2145758539] 'process raft request' (duration: 143.362622ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:49:24.018491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12701} {"level":"info","ts":"2026-05-19T22:49:24.095989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12701,"took":"74.308818ms","hash":2377523862,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":27054080,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-19T22:49:24.096068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2377523862,"revision":12701,"compact-revision":10522} {"level":"info","ts":"2026-05-19T22:49:26.626653Z","caller":"traceutil/trace.go:171","msg":"trace[22349702] transaction","detail":"{read_only:false; response_revision:14813; number_of_response:1; }","duration":"107.800361ms","start":"2026-05-19T22:49:26.518808Z","end":"2026-05-19T22:49:26.626608Z","steps":["trace[22349702] 'process raft request' (duration: 107.585235ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:51:05.834151Z","caller":"traceutil/trace.go:171","msg":"trace[2018878507] transaction","detail":"{read_only:false; response_revision:15888; number_of_response:1; }","duration":"111.971289ms","start":"2026-05-19T22:51:05.722156Z","end":"2026-05-19T22:51:05.834127Z","steps":["trace[2018878507] 'process raft request' (duration: 111.792864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:51:05.834406Z","caller":"traceutil/trace.go:171","msg":"trace[88198029] transaction","detail":"{read_only:false; response_revision:15889; number_of_response:1; }","duration":"112.210717ms","start":"2026-05-19T22:51:05.722178Z","end":"2026-05-19T22:51:05.834389Z","steps":["trace[88198029] 'process raft request' (duration: 111.904688ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:51:05.834466Z","caller":"traceutil/trace.go:171","msg":"trace[922002009] transaction","detail":"{read_only:false; response_revision:15890; number_of_response:1; }","duration":"112.185495ms","start":"2026-05-19T22:51:05.72226Z","end":"2026-05-19T22:51:05.834445Z","steps":["trace[922002009] 'process raft request' (duration: 112.056172ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:51:06.598612Z","caller":"traceutil/trace.go:171","msg":"trace[2006980643] transaction","detail":"{read_only:false; response_revision:15894; number_of_response:1; }","duration":"156.923997ms","start":"2026-05-19T22:51:06.441662Z","end":"2026-05-19T22:51:06.598586Z","steps":["trace[2006980643] 'process raft request' (duration: 119.852832ms)","trace[2006980643] 'compare' (duration: 36.836149ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:51:06.598636Z","caller":"traceutil/trace.go:171","msg":"trace[1917943513] linearizableReadLoop","detail":"{readStateIndex:16446; appliedIndex:16444; }","duration":"120.952303ms","start":"2026-05-19T22:51:06.477661Z","end":"2026-05-19T22:51:06.598614Z","steps":["trace[1917943513] 'read index received' (duration: 83.863277ms)","trace[1917943513] 'applied index is now lower than readState.Index' (duration: 37.087796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:51:06.598846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.165269ms","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-19T22:51:06.598655Z","caller":"traceutil/trace.go:171","msg":"trace[1581199516] transaction","detail":"{read_only:false; response_revision:15895; number_of_response:1; }","duration":"156.561757ms","start":"2026-05-19T22:51:06.44207Z","end":"2026-05-19T22:51:06.598632Z","steps":["trace[1581199516] 'process raft request' (duration: 156.454664ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:51:06.598893Z","caller":"traceutil/trace.go:171","msg":"trace[1428134903] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15896; }","duration":"121.253831ms","start":"2026-05-19T22:51:06.477627Z","end":"2026-05-19T22:51:06.598881Z","steps":["trace[1428134903] 'agreement among raft nodes before linearized reading' (duration: 121.078887ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:54:24.025419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14698} {"level":"info","ts":"2026-05-19T22:54:24.113798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14698,"took":"86.27692ms","hash":2565973217,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":33914880,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T22:54:24.113887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2565973217,"revision":14698,"compact-revision":12701} {"level":"warn","ts":"2026-05-19T22:56:48.607865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.25487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T22:56:48.607982Z","caller":"traceutil/trace.go:171","msg":"trace[757351266] linearizableReadLoop","detail":"{readStateIndex:19044; appliedIndex:19043; }","duration":"461.484689ms","start":"2026-05-19T22:56:48.146483Z","end":"2026-05-19T22:56:48.607967Z","steps":["trace[757351266] 'read index received' (duration: 238.961431ms)","trace[757351266] 'applied index is now lower than readState.Index' (duration: 222.522218ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:56:48.608071Z","caller":"traceutil/trace.go:171","msg":"trace[669014272] transaction","detail":"{read_only:false; response_revision:18410; number_of_response:1; }","duration":"469.267958ms","start":"2026-05-19T22:56:48.138791Z","end":"2026-05-19T22:56:48.608059Z","steps":["trace[669014272] 'process raft request' (duration: 246.734489ms)","trace[669014272] 'compare' (duration: 221.774587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:56:48.608135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:56:48.138772Z","time spent":"469.33307ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:56:48.608364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.88804ms","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-19T22:56:48.608646Z","caller":"traceutil/trace.go:171","msg":"trace[745533524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18410; }","duration":"462.197569ms","start":"2026-05-19T22:56:48.146433Z","end":"2026-05-19T22:56:48.60863Z","steps":["trace[745533524] 'agreement among raft nodes before linearized reading' (duration: 461.816128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.608702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:56:48.146414Z","time spent":"462.274911ms","remote":"127.0.0.1:37342","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-05-19T22:56:48.608696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.660013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T22:56:48.60878Z","caller":"traceutil/trace.go:171","msg":"trace[277085598] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:18410; }","duration":"439.783527ms","start":"2026-05-19T22:56:48.168973Z","end":"2026-05-19T22:56:48.608756Z","steps":["trace[277085598] 'agreement among raft nodes before linearized reading' (duration: 439.631453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.608832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:56:48.168956Z","time spent":"439.86509ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:56:48.608905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.366939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-19T22:56:48.608954Z","caller":"traceutil/trace.go:171","msg":"trace[1173929592] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18410; }","duration":"235.451911ms","start":"2026-05-19T22:56:48.373492Z","end":"2026-05-19T22:56:48.608944Z","steps":["trace[1173929592] 'agreement among raft nodes before linearized reading' (duration: 235.322358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.609054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.87658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T22:56:48.609093Z","caller":"traceutil/trace.go:171","msg":"trace[1105991071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18410; }","duration":"259.939642ms","start":"2026-05-19T22:56:48.349144Z","end":"2026-05-19T22:56:48.609084Z","steps":["trace[1105991071] 'agreement among raft nodes before linearized reading' (duration: 259.884191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.609196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.643892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T22:56:48.609233Z","caller":"traceutil/trace.go:171","msg":"trace[534189477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18410; }","duration":"194.716714ms","start":"2026-05-19T22:56:48.414508Z","end":"2026-05-19T22:56:48.609225Z","steps":["trace[534189477] 'agreement among raft nodes before linearized reading' (duration: 194.612741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.609458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.80534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T22:56:48.609501Z","caller":"traceutil/trace.go:171","msg":"trace[472327926] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:18410; }","duration":"361.887543ms","start":"2026-05-19T22:56:48.247604Z","end":"2026-05-19T22:56:48.609491Z","steps":["trace[472327926] 'agreement among raft nodes before linearized reading' (duration: 361.80774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.609478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.739269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-klwzj\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-05-19T22:56:48.60961Z","caller":"traceutil/trace.go:171","msg":"trace[307038512] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-klwzj; range_end:; response_count:1; response_revision:18410; }","duration":"405.886173ms","start":"2026-05-19T22:56:48.203693Z","end":"2026-05-19T22:56:48.609579Z","steps":["trace[307038512] 'agreement among raft nodes before linearized reading' (duration: 405.502422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.609673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:56:48.203652Z","time spent":"406.009666ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22116,"request content":"key:\"/registry/pods/openstack/nova-compute-default-klwzj\" "} {"level":"warn","ts":"2026-05-19T22:56:48.609515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.028284ms","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-19T22:56:48.609807Z","caller":"traceutil/trace.go:171","msg":"trace[1725446219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18410; }","duration":"195.357663ms","start":"2026-05-19T22:56:48.414437Z","end":"2026-05-19T22:56:48.609795Z","steps":["trace[1725446219] 'agreement among raft nodes before linearized reading' (duration: 194.957152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:56:48.60953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:56:48.247583Z","time spent":"361.940684ms","remote":"127.0.0.1:37542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:59:23.682733Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T22:59:23.689218Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T22:59:23.689307Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T22:59:24.031861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17443} {"level":"info","ts":"2026-05-19T22:59:24.131531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17443,"took":"96.444559ms","hash":265549764,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T22:59:24.131597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":265549764,"revision":17443,"compact-revision":14698} {"level":"info","ts":"2026-05-19T23:04:24.038592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19330} {"level":"info","ts":"2026-05-19T23:04:24.132271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19330,"took":"90.635485ms","hash":4137436819,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":25464832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T23:04:24.132412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137436819,"revision":19330,"compact-revision":17443}