{"level":"warn","ts":"2026-10-01T06:20:51.145225Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T06:20:51.145381Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.38:2380","--initial-cluster=instance=https://162.253.55.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.38: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-10-01T06:20:51.145483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T06:20:51.145493Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-01T06:20:51.145523Z","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-10-01T06:20:51.146381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"]} {"level":"info","ts":"2026-10-01T06:20:51.146519Z","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://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.38: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-10-01T06:20:51.152359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.325479ms"} {"level":"info","ts":"2026-10-01T06:20:51.163218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30b39d71f9e69e7c","cluster-id":"2afc6404f924db16"} {"level":"info","ts":"2026-10-01T06:20:51.163366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=()"} {"level":"info","ts":"2026-10-01T06:20:51.163398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 0"} {"level":"info","ts":"2026-10-01T06:20:51.163436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30b39d71f9e69e7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-01T06:20:51.163452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 1"} {"level":"info","ts":"2026-10-01T06:20:51.163516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"warn","ts":"2026-10-01T06:20:51.178869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-01T06:20:51.183624Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-01T06:20:51.18592Z","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-10-01T06:20:51.191406Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30b39d71f9e69e7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-01T06:20:51.191759Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30b39d71f9e69e7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-01T06:20:51.19185Z","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-10-01T06:20:51.192061Z","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-10-01T06:20:51.19215Z","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-10-01T06:20:51.193626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"info","ts":"2026-10-01T06:20:51.193831Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","added-peer-id":"30b39d71f9e69e7c","added-peer-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-01T06:20:51.197211Z","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-10-01T06:20:51.197549Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30b39d71f9e69e7c","initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-01T06:20:51.197603Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-01T06:20:51.197833Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-01T06:20:51.197853Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-01T06:20:51.364757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c is starting a new election at term 1"} {"level":"info","ts":"2026-10-01T06:20:51.364842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-01T06:20:51.364872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgPreVoteResp from 30b39d71f9e69e7c at term 1"} {"level":"info","ts":"2026-10-01T06:20:51.364892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became candidate at term 2"} {"level":"info","ts":"2026-10-01T06:20:51.36491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgVoteResp from 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-01T06:20:51.364928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became leader at term 2"} {"level":"info","ts":"2026-10-01T06:20:51.364945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30b39d71f9e69e7c elected leader 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-01T06:20:51.36671Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30b39d71f9e69e7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.38:2379]}","request-path":"/0/members/30b39d71f9e69e7c/attributes","cluster-id":"2afc6404f924db16","publish-timeout":"7s"} {"level":"info","ts":"2026-10-01T06:20:51.366745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T06:20:51.366879Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:51.366875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T06:20:51.367201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-01T06:20:51.367339Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-01T06:20:51.368148Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:51.368297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:51.368329Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:51.369739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-01T06:20:51.371262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.38:2379"} {"level":"info","ts":"2026-10-01T06:21:06.58188Z","caller":"traceutil/trace.go:171","msg":"trace[1922164784] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"153.111974ms","start":"2026-10-01T06:21:06.428745Z","end":"2026-10-01T06:21:06.581857Z","steps":["trace[1922164784] 'process raft request' (duration: 152.948422ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:17.264632Z","caller":"traceutil/trace.go:171","msg":"trace[451797174] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"123.18973ms","start":"2026-10-01T06:21:17.14141Z","end":"2026-10-01T06:21:17.264599Z","steps":["trace[451797174] 'process raft request' (duration: 123.035708ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:31.725504Z","caller":"traceutil/trace.go:171","msg":"trace[1369765374] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"105.315843ms","start":"2026-10-01T06:21:31.62015Z","end":"2026-10-01T06:21:31.725466Z","steps":["trace[1369765374] 'read index received' (duration: 87.572625ms)","trace[1369765374] 'applied index is now lower than readState.Index' (duration: 17.741898ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:31.72564Z","caller":"traceutil/trace.go:171","msg":"trace[1678604748] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"148.98621ms","start":"2026-10-01T06:21:31.576642Z","end":"2026-10-01T06:21:31.725628Z","steps":["trace[1678604748] 'process raft request' (duration: 131.08659ms)","trace[1678604748] 'compare' (duration: 17.582936ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:21:31.725935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.083371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:31.725908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.411334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:31.726128Z","caller":"traceutil/trace.go:171","msg":"trace[767724705] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"105.719038ms","start":"2026-10-01T06:21:31.620382Z","end":"2026-10-01T06:21:31.726101Z","steps":["trace[767724705] 'agreement among raft nodes before linearized reading' (duration: 105.279653ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:31.726168Z","caller":"traceutil/trace.go:171","msg":"trace[778594194] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"105.257683ms","start":"2026-10-01T06:21:31.620772Z","end":"2026-10-01T06:21:31.726029Z","steps":["trace[778594194] 'agreement among raft nodes before linearized reading' (duration: 105.068421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:31.726156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.970551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:31.726271Z","caller":"traceutil/trace.go:171","msg":"trace[159230708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"106.126544ms","start":"2026-10-01T06:21:31.620121Z","end":"2026-10-01T06:21:31.726248Z","steps":["trace[159230708] 'agreement among raft nodes before linearized reading' (duration: 105.967802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.186897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.876836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.186973Z","caller":"traceutil/trace.go:171","msg":"trace[244067329] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:582; }","duration":"266.989467ms","start":"2026-10-01T06:21:31.919968Z","end":"2026-10-01T06:21:32.186958Z","steps":["trace[244067329] 'count revisions from in-memory index tree' (duration: 266.740573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.18712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.035238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.187192Z","caller":"traceutil/trace.go:171","msg":"trace[2062514336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:582; }","duration":"267.128889ms","start":"2026-10-01T06:21:31.920046Z","end":"2026-10-01T06:21:32.187175Z","steps":["trace[2062514336] 'range keys from in-memory index tree' (duration: 267.006227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.187177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.988589ms","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-10-01T06:21:32.18725Z","caller":"traceutil/trace.go:171","msg":"trace[664806179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"268.135181ms","start":"2026-10-01T06:21:31.919098Z","end":"2026-10-01T06:21:32.187233Z","steps":["trace[664806179] 'range keys from in-memory index tree' (duration: 267.771797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.187293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.846549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vbp8t\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-01T06:21:32.187317Z","caller":"traceutil/trace.go:171","msg":"trace[1970561108] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vbp8t; range_end:; response_count:1; response_revision:582; }","duration":"101.897179ms","start":"2026-10-01T06:21:32.085412Z","end":"2026-10-01T06:21:32.187309Z","steps":["trace[1970561108] 'range keys from in-memory index tree' (duration: 101.729987ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.260427Z","caller":"traceutil/trace.go:171","msg":"trace[712874474] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"109.867898ms","start":"2026-10-01T06:21:32.15054Z","end":"2026-10-01T06:21:32.260408Z","steps":["trace[712874474] 'read index received' (duration: 108.921136ms)","trace[712874474] 'applied index is now lower than readState.Index' (duration: 945.652µs)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:21:32.260559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.9947ms","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-10-01T06:21:32.260537Z","caller":"traceutil/trace.go:171","msg":"trace[1447127355] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"130.598563ms","start":"2026-10-01T06:21:32.129903Z","end":"2026-10-01T06:21:32.260502Z","steps":["trace[1447127355] 'process raft request' (duration: 129.716021ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.260599Z","caller":"traceutil/trace.go:171","msg":"trace[890531029] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:583; }","duration":"110.064801ms","start":"2026-10-01T06:21:32.150524Z","end":"2026-10-01T06:21:32.260589Z","steps":["trace[890531029] 'agreement among raft nodes before linearized reading' (duration: 109.98295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.791062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.081842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:21:32.791299Z","caller":"traceutil/trace.go:171","msg":"trace[1588184113] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"461.185419ms","start":"2026-10-01T06:21:32.330098Z","end":"2026-10-01T06:21:32.791283Z","steps":["trace[1588184113] 'process raft request' (duration: 461.144428ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.791343Z","caller":"traceutil/trace.go:171","msg":"trace[490951019] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"583.891415ms","start":"2026-10-01T06:21:32.20742Z","end":"2026-10-01T06:21:32.791312Z","steps":["trace[490951019] 'process raft request' (duration: 583.751323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.791374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.330078Z","time spent":"461.26843ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-01T06:21:32.791453Z","caller":"traceutil/trace.go:171","msg":"trace[1630761614] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"597.582162ms","start":"2026-10-01T06:21:32.193826Z","end":"2026-10-01T06:21:32.791408Z","steps":["trace[1630761614] 'process raft request' (duration: 98.492108ms)","trace[1630761614] 'compare' (duration: 497.90684ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:32.79152Z","caller":"traceutil/trace.go:171","msg":"trace[1715480751] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"531.017586ms","start":"2026-10-01T06:21:32.260486Z","end":"2026-10-01T06:21:32.791504Z","steps":["trace[1715480751] 'read index received' (duration: 31.75031ms)","trace[1715480751] 'applied index is now lower than readState.Index' (duration: 499.265566ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:21:32.791594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.927899ms","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-10-01T06:21:32.791636Z","caller":"traceutil/trace.go:171","msg":"trace[245472322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"599.009831ms","start":"2026-10-01T06:21:32.192613Z","end":"2026-10-01T06:21:32.791623Z","steps":["trace[245472322] 'agreement among raft nodes before linearized reading' (duration: 598.95127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.791628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.193809Z","time spent":"597.730055ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:21:32.791674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.192589Z","time spent":"599.077381ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-01T06:21:32.79169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.953693ms","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-10-01T06:21:32.791721Z","caller":"traceutil/trace.go:171","msg":"trace[243342418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:586; }","duration":"640.031434ms","start":"2026-10-01T06:21:32.151679Z","end":"2026-10-01T06:21:32.79171Z","steps":["trace[243342418] 'agreement among raft nodes before linearized reading' (duration: 639.989073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.791759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.151654Z","time spent":"640.096714ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-10-01T06:21:32.791642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.090622ms","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":"warn","ts":"2026-10-01T06:21:32.79188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.451778ms","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-10-01T06:21:32.791911Z","caller":"traceutil/trace.go:171","msg":"trace[40868570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"560.507528ms","start":"2026-10-01T06:21:32.231395Z","end":"2026-10-01T06:21:32.791902Z","steps":["trace[40868570] 'agreement among raft nodes before linearized reading' (duration: 560.432507ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.791899Z","caller":"traceutil/trace.go:171","msg":"trace[887047003] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"599.385735ms","start":"2026-10-01T06:21:32.192493Z","end":"2026-10-01T06:21:32.791878Z","steps":["trace[887047003] 'agreement among raft nodes before linearized reading' (duration: 599.091681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.7919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.366506ms","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":"warn","ts":"2026-10-01T06:21:32.791946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.231379Z","time spent":"560.559809ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-01T06:21:32.791966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.192479Z","time spent":"599.469376ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-01T06:21:32.79198Z","caller":"traceutil/trace.go:171","msg":"trace[457862775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"560.495788ms","start":"2026-10-01T06:21:32.231465Z","end":"2026-10-01T06:21:32.791961Z","steps":["trace[457862775] 'agreement among raft nodes before linearized reading' (duration: 560.315886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.791826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.334546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.792032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.882949ms","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":"warn","ts":"2026-10-01T06:21:32.792057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.3824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.792098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.077553ms","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-10-01T06:21:32.792104Z","caller":"traceutil/trace.go:171","msg":"trace[647980840] 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:586; }","duration":"268.998491ms","start":"2026-10-01T06:21:32.523082Z","end":"2026-10-01T06:21:32.792081Z","steps":["trace[647980840] 'agreement among raft nodes before linearized reading' (duration: 268.861969ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792139Z","caller":"traceutil/trace.go:171","msg":"trace[49851675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"560.156584ms","start":"2026-10-01T06:21:32.231972Z","end":"2026-10-01T06:21:32.792129Z","steps":["trace[49851675] 'agreement among raft nodes before linearized reading' (duration: 560.102804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.430206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.792204Z","caller":"traceutil/trace.go:171","msg":"trace[1852377557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:586; }","duration":"172.498696ms","start":"2026-10-01T06:21:32.619695Z","end":"2026-10-01T06:21:32.792193Z","steps":["trace[1852377557] 'agreement among raft nodes before linearized reading' (duration: 172.442745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.600918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.792279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.755962ms","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-10-01T06:21:32.792302Z","caller":"traceutil/trace.go:171","msg":"trace[273859647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:586; }","duration":"172.662798ms","start":"2026-10-01T06:21:32.619626Z","end":"2026-10-01T06:21:32.792289Z","steps":["trace[273859647] 'agreement among raft nodes before linearized reading' (duration: 172.597807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.79229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.034893ms","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":"warn","ts":"2026-10-01T06:21:32.792302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.313178ms","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-10-01T06:21:32.792322Z","caller":"traceutil/trace.go:171","msg":"trace[947099148] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"296.817042ms","start":"2026-10-01T06:21:32.495491Z","end":"2026-10-01T06:21:32.792308Z","steps":["trace[947099148] 'agreement among raft nodes before linearized reading' (duration: 296.750532ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792363Z","caller":"traceutil/trace.go:171","msg":"trace[129042515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"363.411869ms","start":"2026-10-01T06:21:32.42893Z","end":"2026-10-01T06:21:32.792342Z","steps":["trace[129042515] 'agreement among raft nodes before linearized reading' (duration: 363.294967ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792363Z","caller":"traceutil/trace.go:171","msg":"trace[716489109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"296.135264ms","start":"2026-10-01T06:21:32.496206Z","end":"2026-10-01T06:21:32.792342Z","steps":["trace[716489109] 'agreement among raft nodes before linearized reading' (duration: 296.034493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.543279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.7924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.428917Z","time spent":"363.47287ms","remote":"127.0.0.1:59908","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-10-01T06:21:32.792432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.818182ms","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":"info","ts":"2026-10-01T06:21:32.792434Z","caller":"traceutil/trace.go:171","msg":"trace[1762199719] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:586; }","duration":"217.61318ms","start":"2026-10-01T06:21:32.574805Z","end":"2026-10-01T06:21:32.792418Z","steps":["trace[1762199719] 'agreement among raft nodes before linearized reading' (duration: 217.528959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.207403Z","time spent":"584.031706ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:21:32.792471Z","caller":"traceutil/trace.go:171","msg":"trace[559597920] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"296.879063ms","start":"2026-10-01T06:21:32.495581Z","end":"2026-10-01T06:21:32.79246Z","steps":["trace[559597920] 'agreement among raft nodes before linearized reading' (duration: 296.820393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.382758ms","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-10-01T06:21:32.79252Z","caller":"traceutil/trace.go:171","msg":"trace[1713494508] 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:586; }","duration":"270.440498ms","start":"2026-10-01T06:21:32.52207Z","end":"2026-10-01T06:21:32.79251Z","steps":["trace[1713494508] 'agreement among raft nodes before linearized reading' (duration: 270.388027ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792082Z","caller":"traceutil/trace.go:171","msg":"trace[294989871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"446.62584ms","start":"2026-10-01T06:21:32.34544Z","end":"2026-10-01T06:21:32.792066Z","steps":["trace[294989871] 'agreement among raft nodes before linearized reading' (duration: 446.290446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.324048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.79257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.345428Z","time spent":"447.130027ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-10-01T06:21:32.792586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.755835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.792619Z","caller":"traceutil/trace.go:171","msg":"trace[985247510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:586; }","duration":"192.831086ms","start":"2026-10-01T06:21:32.59978Z","end":"2026-10-01T06:21:32.792611Z","steps":["trace[985247510] 'agreement among raft nodes before linearized reading' (duration: 192.776156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.37499ms","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-10-01T06:21:32.792677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.57768ms","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":"warn","ts":"2026-10-01T06:21:32.792697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.269928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.792708Z","caller":"traceutil/trace.go:171","msg":"trace[609207650] 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:586; }","duration":"270.63285ms","start":"2026-10-01T06:21:32.522067Z","end":"2026-10-01T06:21:32.7927Z","steps":["trace[609207650] 'agreement among raft nodes before linearized reading' (duration: 270.58069ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792728Z","caller":"traceutil/trace.go:171","msg":"trace[148625904] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:586; }","duration":"218.316299ms","start":"2026-10-01T06:21:32.574403Z","end":"2026-10-01T06:21:32.792719Z","steps":["trace[148625904] 'agreement among raft nodes before linearized reading' (duration: 218.271188ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792101Z","caller":"traceutil/trace.go:171","msg":"trace[1285725872] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:586; }","duration":"192.450002ms","start":"2026-10-01T06:21:32.59964Z","end":"2026-10-01T06:21:32.79209Z","steps":["trace[1285725872] 'agreement among raft nodes before linearized reading' (duration: 192.374911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.305218ms","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-10-01T06:21:32.792174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.231963Z","time spent":"560.202815ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"info","ts":"2026-10-01T06:21:32.792869Z","caller":"traceutil/trace.go:171","msg":"trace[1280931847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"240.578312ms","start":"2026-10-01T06:21:32.55228Z","end":"2026-10-01T06:21:32.792858Z","steps":["trace[1280931847] 'agreement among raft nodes before linearized reading' (duration: 240.301459ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792695Z","caller":"traceutil/trace.go:171","msg":"trace[1707418792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"240.44407ms","start":"2026-10-01T06:21:32.552239Z","end":"2026-10-01T06:21:32.792683Z","steps":["trace[1707418792] 'agreement among raft nodes before linearized reading' (duration: 240.371919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.835862ms","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-10-01T06:21:32.792979Z","caller":"traceutil/trace.go:171","msg":"trace[97334667] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"239.883993ms","start":"2026-10-01T06:21:32.553086Z","end":"2026-10-01T06:21:32.79297Z","steps":["trace[97334667] 'agreement among raft nodes before linearized reading' (duration: 239.836362ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.792591Z","caller":"traceutil/trace.go:171","msg":"trace[767378799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:586; }","duration":"169.361339ms","start":"2026-10-01T06:21:32.623219Z","end":"2026-10-01T06:21:32.792581Z","steps":["trace[767378799] 'agreement among raft nodes before linearized reading' (duration: 169.310648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.793049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.381768ms","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-10-01T06:21:32.792657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.068676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.793084Z","caller":"traceutil/trace.go:171","msg":"trace[1103835161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"362.452118ms","start":"2026-10-01T06:21:32.430623Z","end":"2026-10-01T06:21:32.793075Z","steps":["trace[1103835161] 'agreement among raft nodes before linearized reading' (duration: 362.398957ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.793089Z","caller":"traceutil/trace.go:171","msg":"trace[1295089003] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:586; }","duration":"218.545662ms","start":"2026-10-01T06:21:32.574534Z","end":"2026-10-01T06:21:32.79308Z","steps":["trace[1295089003] 'agreement among raft nodes before linearized reading' (duration: 218.101877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.79287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.920435ms","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-10-01T06:21:32.793113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.430602Z","time spent":"362.502719ms","remote":"127.0.0.1:59896","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":"warn","ts":"2026-10-01T06:21:32.793128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.690252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.793133Z","caller":"traceutil/trace.go:171","msg":"trace[1286220103] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"364.21817ms","start":"2026-10-01T06:21:32.428901Z","end":"2026-10-01T06:21:32.793119Z","steps":["trace[1286220103] 'agreement among raft nodes before linearized reading' (duration: 363.915726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.792053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.231407Z","time spent":"560.63516ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-01T06:21:32.793168Z","caller":"traceutil/trace.go:171","msg":"trace[1252708923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"446.743862ms","start":"2026-10-01T06:21:32.346409Z","end":"2026-10-01T06:21:32.793153Z","steps":["trace[1252708923] 'agreement among raft nodes before linearized reading' (duration: 446.685171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.793203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.346382Z","time spent":"446.810324ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-10-01T06:21:32.793257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.613902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:21:32.793282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.42888Z","time spent":"364.385642ms","remote":"127.0.0.1:59908","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-10-01T06:21:32.793318Z","caller":"traceutil/trace.go:171","msg":"trace[1658318114] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"447.696434ms","start":"2026-10-01T06:21:32.345601Z","end":"2026-10-01T06:21:32.793297Z","steps":["trace[1658318114] 'agreement among raft nodes before linearized reading' (duration: 447.578272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.793372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.345592Z","time spent":"447.770245ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-01T06:21:32.793372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.7898ms","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-10-01T06:21:32.793386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.860251ms","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":"warn","ts":"2026-10-01T06:21:32.7934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.315075ms","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-10-01T06:21:32.793414Z","caller":"traceutil/trace.go:171","msg":"trace[1975786888] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"396.889691ms","start":"2026-10-01T06:21:32.396515Z","end":"2026-10-01T06:21:32.793404Z","steps":["trace[1975786888] 'agreement among raft nodes before linearized reading' (duration: 396.841851ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.793408Z","caller":"traceutil/trace.go:171","msg":"trace[215339973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"396.86657ms","start":"2026-10-01T06:21:32.396531Z","end":"2026-10-01T06:21:32.793398Z","steps":["trace[215339973] 'agreement among raft nodes before linearized reading' (duration: 396.808729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.793445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.396496Z","time spent":"396.940832ms","remote":"127.0.0.1:59896","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-10-01T06:21:32.793457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.396506Z","time spent":"396.938712ms","remote":"127.0.0.1:59896","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-10-01T06:21:32.793446Z","caller":"traceutil/trace.go:171","msg":"trace[1645617434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"375.516189ms","start":"2026-10-01T06:21:32.417915Z","end":"2026-10-01T06:21:32.793431Z","steps":["trace[1645617434] 'agreement among raft nodes before linearized reading' (duration: 375.122494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.794084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.417905Z","time spent":"376.163856ms","remote":"127.0.0.1:33642","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-10-01T06:21:32.792147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.510892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:32.794325Z","caller":"traceutil/trace.go:171","msg":"trace[980536161] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:586; }","duration":"643.7383ms","start":"2026-10-01T06:21:32.150569Z","end":"2026-10-01T06:21:32.794307Z","steps":["trace[980536161] 'agreement among raft nodes before linearized reading' (duration: 641.550993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.794366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.150534Z","time spent":"643.81644ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-10-01T06:21:34.428175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.046097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:21:34.428572Z","caller":"traceutil/trace.go:171","msg":"trace[1019750375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:610; }","duration":"114.498222ms","start":"2026-10-01T06:21:34.31405Z","end":"2026-10-01T06:21:34.428548Z","steps":["trace[1019750375] 'range keys from in-memory index tree' (duration: 113.924775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:49.58822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.229643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:21:49.588362Z","caller":"traceutil/trace.go:171","msg":"trace[654961249] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"385.041366ms","start":"2026-10-01T06:21:49.203297Z","end":"2026-10-01T06:21:49.588338Z","steps":["trace[654961249] 'process raft request' (duration: 188.590411ms)","trace[654961249] 'compare' (duration: 196.040921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:21:49.588436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:49.203274Z","time spent":"385.124927ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:22.556861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.895341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a747315-2255-425c-8a2a-ca405ac238f7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-01T06:23:22.557055Z","caller":"traceutil/trace.go:171","msg":"trace[1565778187] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a747315-2255-425c-8a2a-ca405ac238f7; range_end:; response_count:1; response_revision:1881; }","duration":"101.067813ms","start":"2026-10-01T06:23:22.455893Z","end":"2026-10-01T06:23:22.556961Z","steps":["trace[1565778187] 'range keys from in-memory index tree' (duration: 100.486855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:23.361055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.996405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:23.361359Z","caller":"traceutil/trace.go:171","msg":"trace[679257211] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"560.473345ms","start":"2026-10-01T06:23:22.800841Z","end":"2026-10-01T06:23:23.361314Z","steps":["trace[679257211] 'process raft request' (duration: 371.620801ms)","trace[679257211] 'compare' (duration: 187.843563ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:23.361523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:22.800824Z","time spent":"560.612357ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:23.361569Z","caller":"traceutil/trace.go:171","msg":"trace[1024204795] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"187.768423ms","start":"2026-10-01T06:23:23.173779Z","end":"2026-10-01T06:23:23.361547Z","steps":["trace[1024204795] 'read index received' (duration: 101.290815ms)","trace[1024204795] 'applied index is now lower than readState.Index' (duration: 86.476458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:23.361945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.543716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T06:23:23.361958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.176027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-01T06:23:23.362049Z","caller":"traceutil/trace.go:171","msg":"trace[756270350] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1884; }","duration":"167.633027ms","start":"2026-10-01T06:23:23.194347Z","end":"2026-10-01T06:23:23.36198Z","steps":["trace[756270350] 'agreement among raft nodes before linearized reading' (duration: 167.555316ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:23.362073Z","caller":"traceutil/trace.go:171","msg":"trace[207748912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1884; }","duration":"188.337458ms","start":"2026-10-01T06:23:23.173722Z","end":"2026-10-01T06:23:23.36206Z","steps":["trace[207748912] 'agreement among raft nodes before linearized reading' (duration: 188.102176ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:23.361937Z","caller":"traceutil/trace.go:171","msg":"trace[1049692249] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"519.329895ms","start":"2026-10-01T06:23:22.842596Z","end":"2026-10-01T06:23:23.361925Z","steps":["trace[1049692249] 'process raft request' (duration: 518.770398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:23.362238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:22.842575Z","time spent":"519.533847ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:23.778302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.033104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:23.778544Z","caller":"traceutil/trace.go:171","msg":"trace[1012655138] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1967; }","duration":"214.602382ms","start":"2026-10-01T06:23:23.563925Z","end":"2026-10-01T06:23:23.778527Z","steps":["trace[1012655138] 'read index received' (duration: 51.184063ms)","trace[1012655138] 'applied index is now lower than readState.Index' (duration: 163.417149ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:23.778625Z","caller":"traceutil/trace.go:171","msg":"trace[1727031348] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"329.144935ms","start":"2026-10-01T06:23:23.449471Z","end":"2026-10-01T06:23:23.778616Z","steps":["trace[1727031348] 'process raft request' (duration: 328.945493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:23.778692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:23.44945Z","time spent":"329.207435ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:23.778672Z","caller":"traceutil/trace.go:171","msg":"trace[392987577] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"262.865943ms","start":"2026-10-01T06:23:23.515779Z","end":"2026-10-01T06:23:23.778645Z","steps":["trace[392987577] 'process raft request' (duration: 262.691021ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:23.778676Z","caller":"traceutil/trace.go:171","msg":"trace[1005647997] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"329.189915ms","start":"2026-10-01T06:23:23.44945Z","end":"2026-10-01T06:23:23.77864Z","steps":["trace[1005647997] 'process raft request' (duration: 165.728426ms)","trace[1005647997] 'compare' (duration: 162.877962ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:23.778836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:23.449423Z","time spent":"329.334027ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:23.77894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.986856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a747315-2255-425c-8a2a-ca405ac238f7\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-01T06:23:23.779069Z","caller":"traceutil/trace.go:171","msg":"trace[1797757657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a747315-2255-425c-8a2a-ca405ac238f7; range_end:; response_count:1; response_revision:1893; }","duration":"215.161379ms","start":"2026-10-01T06:23:23.563889Z","end":"2026-10-01T06:23:23.77905Z","steps":["trace[1797757657] 'agreement among raft nodes before linearized reading' (duration: 214.856325ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:23.795778Z","caller":"traceutil/trace.go:171","msg":"trace[1560565864] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"116.266581ms","start":"2026-10-01T06:23:23.67949Z","end":"2026-10-01T06:23:23.795757Z","steps":["trace[1560565864] 'process raft request' (duration: 116.137749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:23.803881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.17546ms","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-10-01T06:23:23.803958Z","caller":"traceutil/trace.go:171","msg":"trace[1028673558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1895; }","duration":"191.305452ms","start":"2026-10-01T06:23:23.612639Z","end":"2026-10-01T06:23:23.803944Z","steps":["trace[1028673558] 'agreement among raft nodes before linearized reading' (duration: 191.13951ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:24:09.847311Z","caller":"traceutil/trace.go:171","msg":"trace[385189246] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"146.216092ms","start":"2026-10-01T06:24:09.701063Z","end":"2026-10-01T06:24:09.84728Z","steps":["trace[385189246] 'process raft request' (duration: 145.97795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:24:10.580803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.583592ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-01T06:24:10.58092Z","caller":"traceutil/trace.go:171","msg":"trace[1777075169] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2284; }","duration":"173.835705ms","start":"2026-10-01T06:24:10.407061Z","end":"2026-10-01T06:24:10.580897Z","steps":["trace[1777075169] 'read index received' (duration: 21.096032ms)","trace[1777075169] 'applied index is now lower than readState.Index' (duration: 152.738273ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:24:10.581035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.941856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:24:10.581074Z","caller":"traceutil/trace.go:171","msg":"trace[17774665] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2196; }","duration":"174.108068ms","start":"2026-10-01T06:24:10.406951Z","end":"2026-10-01T06:24:10.581059Z","steps":["trace[17774665] 'agreement among raft nodes before linearized reading' (duration: 173.999957ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:24:16.357868Z","caller":"traceutil/trace.go:171","msg":"trace[465710124] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"134.528551ms","start":"2026-10-01T06:24:16.223314Z","end":"2026-10-01T06:24:16.357843Z","steps":["trace[465710124] 'process raft request' (duration: 134.388049ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:24:16.418968Z","caller":"traceutil/trace.go:171","msg":"trace[22812801] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"195.214504ms","start":"2026-10-01T06:24:16.223714Z","end":"2026-10-01T06:24:16.418929Z","steps":["trace[22812801] 'process raft request' (duration: 195.050302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:24:22.81829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.377465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:24:22.818477Z","caller":"traceutil/trace.go:171","msg":"trace[1516349297] linearizableReadLoop","detail":"{readStateIndex:2351; appliedIndex:2350; }","duration":"158.68266ms","start":"2026-10-01T06:24:22.65977Z","end":"2026-10-01T06:24:22.818453Z","steps":["trace[1516349297] 'read index received' (duration: 71.251µs)","trace[1516349297] 'applied index is now lower than readState.Index' (duration: 158.609649ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:24:22.818608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.839412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:24:22.81866Z","caller":"traceutil/trace.go:171","msg":"trace[562546389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2260; }","duration":"158.897663ms","start":"2026-10-01T06:24:22.659748Z","end":"2026-10-01T06:24:22.818646Z","steps":["trace[562546389] 'agreement among raft nodes before linearized reading' (duration: 158.790811ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:24:22.818706Z","caller":"traceutil/trace.go:171","msg":"trace[305385808] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"347.535141ms","start":"2026-10-01T06:24:22.47112Z","end":"2026-10-01T06:24:22.818655Z","steps":["trace[305385808] 'process raft request' (duration: 135.69319ms)","trace[305385808] 'compare' (duration: 211.253314ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:24:22.818873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:24:22.471105Z","time spent":"347.682703ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:26:24.651739Z","caller":"traceutil/trace.go:171","msg":"trace[2112459006] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"136.750104ms","start":"2026-10-01T06:26:24.514957Z","end":"2026-10-01T06:26:24.651708Z","steps":["trace[2112459006] 'process raft request' (duration: 136.57258ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:26:24.77859Z","caller":"traceutil/trace.go:171","msg":"trace[423175265] transaction","detail":"{read_only:false; response_revision:3098; number_of_response:1; }","duration":"259.403956ms","start":"2026-10-01T06:26:24.519168Z","end":"2026-10-01T06:26:24.778572Z","steps":["trace[423175265] 'process raft request' (duration: 259.027357ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:26:24.779059Z","caller":"traceutil/trace.go:171","msg":"trace[2017945875] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"255.637832ms","start":"2026-10-01T06:26:24.523393Z","end":"2026-10-01T06:26:24.77903Z","steps":["trace[2017945875] 'process raft request' (duration: 255.143569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:26:24.779329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.718062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-01T06:26:24.779426Z","caller":"traceutil/trace.go:171","msg":"trace[1532250527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3099; }","duration":"221.875906ms","start":"2026-10-01T06:26:24.557529Z","end":"2026-10-01T06:26:24.779405Z","steps":["trace[1532250527] 'agreement among raft nodes before linearized reading' (duration: 221.594809ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:26:24.779445Z","caller":"traceutil/trace.go:171","msg":"trace[1489015567] linearizableReadLoop","detail":"{readStateIndex:3222; appliedIndex:3219; }","duration":"221.167448ms","start":"2026-10-01T06:26:24.557583Z","end":"2026-10-01T06:26:24.778751Z","steps":["trace[1489015567] 'read index received' (duration: 93.962062ms)","trace[1489015567] 'applied index is now lower than readState.Index' (duration: 127.204316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:26:25.146803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.44863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:26:25.146947Z","caller":"traceutil/trace.go:171","msg":"trace[1398510022] linearizableReadLoop","detail":"{readStateIndex:3226; appliedIndex:3225; }","duration":"220.061672ms","start":"2026-10-01T06:26:24.92686Z","end":"2026-10-01T06:26:25.146922Z","steps":["trace[1398510022] 'read index received' (duration: 65.267853ms)","trace[1398510022] 'applied index is now lower than readState.Index' (duration: 154.791289ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:26:25.147142Z","caller":"traceutil/trace.go:171","msg":"trace[1064535422] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"296.338734ms","start":"2026-10-01T06:26:24.850785Z","end":"2026-10-01T06:26:25.147124Z","steps":["trace[1064535422] 'process raft request' (duration: 141.415931ms)","trace[1064535422] 'compare' (duration: 154.202534ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:26:25.147265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.859145ms","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-10-01T06:26:25.147349Z","caller":"traceutil/trace.go:171","msg":"trace[553336584] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3103; }","duration":"103.992449ms","start":"2026-10-01T06:26:25.043335Z","end":"2026-10-01T06:26:25.147327Z","steps":["trace[553336584] 'agreement among raft nodes before linearized reading' (duration: 103.827115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:26:25.147496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.616237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-01T06:26:25.147566Z","caller":"traceutil/trace.go:171","msg":"trace[1510121878] range","detail":"{range_begin:/registry/masterleases/162.253.55.38; range_end:; response_count:1; response_revision:3103; }","duration":"220.74703ms","start":"2026-10-01T06:26:24.926803Z","end":"2026-10-01T06:26:25.14755Z","steps":["trace[1510121878] 'agreement among raft nodes before linearized reading' (duration: 220.34984ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:29:19.421121Z","caller":"traceutil/trace.go:171","msg":"trace[1784766261] transaction","detail":"{read_only:false; response_revision:4139; number_of_response:1; }","duration":"100.735122ms","start":"2026-10-01T06:29:19.320357Z","end":"2026-10-01T06:29:19.421092Z","steps":["trace[1784766261] 'process raft request' (duration: 100.524958ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:51.495698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-10-01T06:30:51.583331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"86.593242ms","hash":4133415685,"current-db-size-bytes":42401792,"current-db-size":"42 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-01T06:30:51.583381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4133415685,"revision":2910,"compact-revision":-1} {"level":"warn","ts":"2026-10-01T06:31:20.530055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.251739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14423"} {"level":"info","ts":"2026-10-01T06:31:20.530178Z","caller":"traceutil/trace.go:171","msg":"trace[1382471123] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5458; }","duration":"108.448632ms","start":"2026-10-01T06:31:20.421708Z","end":"2026-10-01T06:31:20.530156Z","steps":["trace[1382471123] 'agreement among raft nodes before linearized reading' (duration: 108.126217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:20.530978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.132533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:73610"} {"level":"info","ts":"2026-10-01T06:31:20.531152Z","caller":"traceutil/trace.go:171","msg":"trace[362918594] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5458; }","duration":"112.303975ms","start":"2026-10-01T06:31:20.418798Z","end":"2026-10-01T06:31:20.531102Z","steps":["trace[362918594] 'agreement among raft nodes before linearized reading' (duration: 111.884659ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:20.673037Z","caller":"traceutil/trace.go:171","msg":"trace[1990057192] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"122.883651ms","start":"2026-10-01T06:31:20.550141Z","end":"2026-10-01T06:31:20.673025Z","steps":["trace[1990057192] 'process raft request' (duration: 122.159439ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:25.402877Z","caller":"traceutil/trace.go:171","msg":"trace[1389573893] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"236.549712ms","start":"2026-10-01T06:31:25.166303Z","end":"2026-10-01T06:31:25.402853Z","steps":["trace[1389573893] 'process raft request' (duration: 236.415519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:25.403007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.284652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:25.403048Z","caller":"traceutil/trace.go:171","msg":"trace[985167922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5598; }","duration":"198.396495ms","start":"2026-10-01T06:31:25.204642Z","end":"2026-10-01T06:31:25.403039Z","steps":["trace[985167922] 'agreement among raft nodes before linearized reading' (duration: 198.285353ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:25.40288Z","caller":"traceutil/trace.go:171","msg":"trace[428185956] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5816; }","duration":"198.186171ms","start":"2026-10-01T06:31:25.204675Z","end":"2026-10-01T06:31:25.402861Z","steps":["trace[428185956] 'read index received' (duration: 198.177421ms)","trace[428185956] 'applied index is now lower than readState.Index' (duration: 7.31µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:25.506786Z","caller":"traceutil/trace.go:171","msg":"trace[1620241523] linearizableReadLoop","detail":"{readStateIndex:5817; appliedIndex:5816; }","duration":"102.413525ms","start":"2026-10-01T06:31:25.404348Z","end":"2026-10-01T06:31:25.506762Z","steps":["trace[1620241523] 'read index received' (duration: 78.344749ms)","trace[1620241523] 'applied index is now lower than readState.Index' (duration: 24.067536ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:25.506845Z","caller":"traceutil/trace.go:171","msg":"trace[520391018] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"276.650602ms","start":"2026-10-01T06:31:25.23016Z","end":"2026-10-01T06:31:25.50681Z","steps":["trace[520391018] 'process raft request' (duration: 252.580437ms)","trace[520391018] 'compare' (duration: 23.905843ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:31:25.506944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.557497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:25.506984Z","caller":"traceutil/trace.go:171","msg":"trace[1894777708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5599; }","duration":"102.661879ms","start":"2026-10-01T06:31:25.404313Z","end":"2026-10-01T06:31:25.506975Z","steps":["trace[1894777708] 'agreement among raft nodes before linearized reading' (duration: 102.559387ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:25.95889Z","caller":"traceutil/trace.go:171","msg":"trace[551624959] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"216.082566ms","start":"2026-10-01T06:31:25.742773Z","end":"2026-10-01T06:31:25.958856Z","steps":["trace[551624959] 'process raft request' (duration: 204.680428ms)","trace[551624959] 'compare' (duration: 11.267496ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:43.405524Z","caller":"traceutil/trace.go:171","msg":"trace[1460526172] linearizableReadLoop","detail":"{readStateIndex:6164; appliedIndex:6163; }","duration":"300.462166ms","start":"2026-10-01T06:31:43.105039Z","end":"2026-10-01T06:31:43.405501Z","steps":["trace[1460526172] 'read index received' (duration: 300.12963ms)","trace[1460526172] 'applied index is now lower than readState.Index' (duration: 331.186µs)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:31:43.406035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.944983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:43.406139Z","caller":"traceutil/trace.go:171","msg":"trace[945385017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5934; }","duration":"301.155177ms","start":"2026-10-01T06:31:43.104965Z","end":"2026-10-01T06:31:43.40612Z","steps":["trace[945385017] 'agreement among raft nodes before linearized reading' (duration: 300.939934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:43.406189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:31:43.104944Z","time spent":"301.232488ms","remote":"127.0.0.1:33368","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-10-01T06:31:43.406176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.047679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:43.406268Z","caller":"traceutil/trace.go:171","msg":"trace[489638319] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5934; }","duration":"271.173961ms","start":"2026-10-01T06:31:43.135081Z","end":"2026-10-01T06:31:43.406255Z","steps":["trace[489638319] 'agreement among raft nodes before linearized reading' (duration: 270.995178ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:43.406477Z","caller":"traceutil/trace.go:171","msg":"trace[2012097221] transaction","detail":"{read_only:false; response_revision:5934; number_of_response:1; }","duration":"357.660892ms","start":"2026-10-01T06:31:43.048802Z","end":"2026-10-01T06:31:43.406463Z","steps":["trace[2012097221] 'process raft request' (duration: 356.429302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:43.406557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:31:43.048782Z","time spent":"357.721883ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:31:43.406819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.416306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:43.406855Z","caller":"traceutil/trace.go:171","msg":"trace[2057204304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5934; }","duration":"205.482127ms","start":"2026-10-01T06:31:43.201363Z","end":"2026-10-01T06:31:43.406845Z","steps":["trace[2057204304] 'agreement among raft nodes before linearized reading' (duration: 205.420926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:43.854599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.867433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T06:31:43.854698Z","caller":"traceutil/trace.go:171","msg":"trace[949231139] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5934; }","duration":"306.031735ms","start":"2026-10-01T06:31:43.548643Z","end":"2026-10-01T06:31:43.854675Z","steps":["trace[949231139] 'count revisions from in-memory index tree' (duration: 305.786342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:43.854751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:31:43.548623Z","time spent":"306.113056ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":76,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-10-01T06:31:43.85484Z","caller":"traceutil/trace.go:171","msg":"trace[1005490702] transaction","detail":"{read_only:false; response_revision:5935; number_of_response:1; }","duration":"275.771336ms","start":"2026-10-01T06:31:43.579039Z","end":"2026-10-01T06:31:43.85481Z","steps":["trace[1005490702] 'process raft request' (duration: 244.234025ms)","trace[1005490702] 'compare' (duration: 31.047082ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:43.854782Z","caller":"traceutil/trace.go:171","msg":"trace[1965573868] linearizableReadLoop","detail":"{readStateIndex:6165; appliedIndex:6164; }","duration":"236.479699ms","start":"2026-10-01T06:31:43.618265Z","end":"2026-10-01T06:31:43.854745Z","steps":["trace[1965573868] 'read index received' (duration: 205.02678ms)","trace[1965573868] 'applied index is now lower than readState.Index' (duration: 31.451259ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:31:43.855042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.766294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-01T06:31:43.855112Z","caller":"traceutil/trace.go:171","msg":"trace[2083936115] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:5935; }","duration":"236.876465ms","start":"2026-10-01T06:31:43.618224Z","end":"2026-10-01T06:31:43.8551Z","steps":["trace[2083936115] 'agreement among raft nodes before linearized reading' (duration: 236.631861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:43.855155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.721111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:31:43.855229Z","caller":"traceutil/trace.go:171","msg":"trace[882608028] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5935; }","duration":"100.842593ms","start":"2026-10-01T06:31:43.754369Z","end":"2026-10-01T06:31:43.855211Z","steps":["trace[882608028] 'agreement among raft nodes before linearized reading' (duration: 100.725051ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:59.205482Z","caller":"traceutil/trace.go:171","msg":"trace[1051189263] linearizableReadLoop","detail":"{readStateIndex:6389; appliedIndex:6388; }","duration":"166.619673ms","start":"2026-10-01T06:31:59.038839Z","end":"2026-10-01T06:31:59.205458Z","steps":["trace[1051189263] 'read index received' (duration: 166.44402ms)","trace[1051189263] 'applied index is now lower than readState.Index' (duration: 174.413µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:59.205537Z","caller":"traceutil/trace.go:171","msg":"trace[125948017] transaction","detail":"{read_only:false; response_revision:6156; number_of_response:1; }","duration":"237.816151ms","start":"2026-10-01T06:31:58.96769Z","end":"2026-10-01T06:31:59.205506Z","steps":["trace[125948017] 'process raft request' (duration: 237.609177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:59.205706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.853867ms","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-10-01T06:31:59.205749Z","caller":"traceutil/trace.go:171","msg":"trace[788614523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6156; }","duration":"166.951979ms","start":"2026-10-01T06:31:59.038787Z","end":"2026-10-01T06:31:59.205739Z","steps":["trace[788614523] 'agreement among raft nodes before linearized reading' (duration: 166.776596ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:32:11.424685Z","caller":"traceutil/trace.go:171","msg":"trace[1691961489] transaction","detail":"{read_only:false; response_revision:6263; number_of_response:1; }","duration":"135.059568ms","start":"2026-10-01T06:32:11.289597Z","end":"2026-10-01T06:32:11.424657Z","steps":["trace[1691961489] 'process raft request' (duration: 113.191652ms)","trace[1691961489] 'compare' (duration: 21.736343ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:32:53.649713Z","caller":"traceutil/trace.go:171","msg":"trace[552185551] transaction","detail":"{read_only:false; response_revision:6612; number_of_response:1; }","duration":"122.216295ms","start":"2026-10-01T06:32:53.527471Z","end":"2026-10-01T06:32:53.649688Z","steps":["trace[552185551] 'process raft request' (duration: 122.004992ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:32:54.500308Z","caller":"traceutil/trace.go:171","msg":"trace[2047942590] linearizableReadLoop","detail":"{readStateIndex:6860; appliedIndex:6859; }","duration":"475.935796ms","start":"2026-10-01T06:32:54.024349Z","end":"2026-10-01T06:32:54.500285Z","steps":["trace[2047942590] 'read index received' (duration: 475.779104ms)","trace[2047942590] 'applied index is now lower than readState.Index' (duration: 155.762µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:32:54.500378Z","caller":"traceutil/trace.go:171","msg":"trace[665339429] transaction","detail":"{read_only:false; response_revision:6614; number_of_response:1; }","duration":"710.165309ms","start":"2026-10-01T06:32:53.790169Z","end":"2026-10-01T06:32:54.500334Z","steps":["trace[665339429] 'process raft request' (duration: 709.961876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:32:54.500509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.149439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-10-01T06:32:54.500519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:32:53.790145Z","time spent":"710.299171ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7366,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:32:54.500546Z","caller":"traceutil/trace.go:171","msg":"trace[1067643284] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:6614; }","duration":"476.237351ms","start":"2026-10-01T06:32:54.024297Z","end":"2026-10-01T06:32:54.500534Z","steps":["trace[1067643284] 'agreement among raft nodes before linearized reading' (duration: 476.101539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:32:54.500577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:32:54.024277Z","time spent":"476.292672ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-10-01T06:32:54.50762Z","caller":"traceutil/trace.go:171","msg":"trace[1671910932] transaction","detail":"{read_only:false; response_revision:6615; number_of_response:1; }","duration":"385.47008ms","start":"2026-10-01T06:32:54.122136Z","end":"2026-10-01T06:32:54.507606Z","steps":["trace[1671910932] 'process raft request' (duration: 385.357848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:32:54.50774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:32:54.122117Z","time spent":"385.546921ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:32:54.508182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.070702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-01T06:32:54.508307Z","caller":"traceutil/trace.go:171","msg":"trace[646124544] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:6618; }","duration":"285.203483ms","start":"2026-10-01T06:32:54.223047Z","end":"2026-10-01T06:32:54.508251Z","steps":["trace[646124544] 'agreement among raft nodes before linearized reading' (duration: 284.98968ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:32:54.508074Z","caller":"traceutil/trace.go:171","msg":"trace[1930106311] transaction","detail":"{read_only:false; response_revision:6616; number_of_response:1; }","duration":"115.829877ms","start":"2026-10-01T06:32:54.392218Z","end":"2026-10-01T06:32:54.508048Z","steps":["trace[1930106311] 'process raft request' (duration: 115.35421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:32:54.508384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.301956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:32:54.508606Z","caller":"traceutil/trace.go:171","msg":"trace[512372949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6618; }","duration":"308.57534ms","start":"2026-10-01T06:32:54.200016Z","end":"2026-10-01T06:32:54.508591Z","steps":["trace[512372949] 'agreement among raft nodes before linearized reading' (duration: 308.306446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:32:54.508693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:32:54.19997Z","time spent":"308.698492ms","remote":"127.0.0.1:33368","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-10-01T06:35:51.504133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4779} {"level":"info","ts":"2026-10-01T06:35:51.567454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4779,"took":"61.813834ms","hash":3345369169,"current-db-size-bytes":42401792,"current-db-size":"42 MB","current-db-size-in-use-bytes":39395328,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-10-01T06:35:51.567521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3345369169,"revision":4779,"compact-revision":2910} {"level":"info","ts":"2026-10-01T06:38:25.474187Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002481.wal"} {"level":"info","ts":"2026-10-01T06:38:27.978869Z","caller":"traceutil/trace.go:171","msg":"trace[744884496] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"102.606282ms","start":"2026-10-01T06:38:27.87624Z","end":"2026-10-01T06:38:27.978846Z","steps":["trace[744884496] 'process raft request' (duration: 102.526611ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:27.978926Z","caller":"traceutil/trace.go:171","msg":"trace[2000215280] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"107.548998ms","start":"2026-10-01T06:38:27.871317Z","end":"2026-10-01T06:38:27.978866Z","steps":["trace[2000215280] 'process raft request' (duration: 107.288645ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:27.979069Z","caller":"traceutil/trace.go:171","msg":"trace[1222628436] linearizableReadLoop","detail":"{readStateIndex:9367; appliedIndex:9364; }","duration":"100.007638ms","start":"2026-10-01T06:38:27.87903Z","end":"2026-10-01T06:38:27.979038Z","steps":["trace[1222628436] 'read index received' (duration: 18.401717ms)","trace[1222628436] 'applied index is now lower than readState.Index' (duration: 81.604111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:38:27.97924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.181319ms","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-10-01T06:38:27.97928Z","caller":"traceutil/trace.go:171","msg":"trace[848338637] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9044; }","duration":"100.284221ms","start":"2026-10-01T06:38:27.878979Z","end":"2026-10-01T06:38:27.979264Z","steps":["trace[848338637] 'agreement among raft nodes before linearized reading' (duration: 100.19345ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:29.640144Z","caller":"traceutil/trace.go:171","msg":"trace[1918064196] transaction","detail":"{read_only:false; response_revision:9050; number_of_response:1; }","duration":"128.70893ms","start":"2026-10-01T06:38:29.511405Z","end":"2026-10-01T06:38:29.640114Z","steps":["trace[1918064196] 'process raft request' (duration: 128.458676ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:29.642282Z","caller":"traceutil/trace.go:171","msg":"trace[2078119200] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"130.373502ms","start":"2026-10-01T06:38:29.511874Z","end":"2026-10-01T06:38:29.642248Z","steps":["trace[2078119200] 'process raft request' (duration: 130.22298ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:41.936651Z","caller":"traceutil/trace.go:171","msg":"trace[2112230047] linearizableReadLoop","detail":"{readStateIndex:9439; appliedIndex:9437; }","duration":"183.544347ms","start":"2026-10-01T06:38:41.753076Z","end":"2026-10-01T06:38:41.93662Z","steps":["trace[2112230047] 'read index received' (duration: 46.427179ms)","trace[2112230047] 'applied index is now lower than readState.Index' (duration: 137.116158ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:38:41.936725Z","caller":"traceutil/trace.go:171","msg":"trace[2077280651] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"227.557764ms","start":"2026-10-01T06:38:41.709122Z","end":"2026-10-01T06:38:41.936679Z","steps":["trace[2077280651] 'process raft request' (duration: 175.410078ms)","trace[2077280651] 'compare' (duration: 51.967363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:38:41.936797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.695219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:38:41.936834Z","caller":"traceutil/trace.go:171","msg":"trace[1422969241] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:9112; }","duration":"183.789831ms","start":"2026-10-01T06:38:41.753032Z","end":"2026-10-01T06:38:41.936822Z","steps":["trace[1422969241] 'agreement among raft nodes before linearized reading' (duration: 183.704099ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:42.816353Z","caller":"traceutil/trace.go:171","msg":"trace[1925166396] transaction","detail":"{read_only:false; response_revision:9118; number_of_response:1; }","duration":"129.431215ms","start":"2026-10-01T06:38:42.68688Z","end":"2026-10-01T06:38:42.816311Z","steps":["trace[1925166396] 'process raft request' (duration: 129.236183ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:38:43.197937Z","caller":"traceutil/trace.go:171","msg":"trace[284192425] transaction","detail":"{read_only:false; response_revision:9119; number_of_response:1; }","duration":"377.060174ms","start":"2026-10-01T06:38:42.820852Z","end":"2026-10-01T06:38:43.197912Z","steps":["trace[284192425] 'process raft request' (duration: 287.798395ms)","trace[284192425] 'compare' (duration: 89.117757ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:38:43.198093Z","caller":"traceutil/trace.go:171","msg":"trace[1477177722] linearizableReadLoop","detail":"{readStateIndex:9447; appliedIndex:9445; }","duration":"206.965087ms","start":"2026-10-01T06:38:42.991107Z","end":"2026-10-01T06:38:43.198073Z","steps":["trace[1477177722] 'read index received' (duration: 117.507346ms)","trace[1477177722] 'applied index is now lower than readState.Index' (duration: 89.455741ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:38:43.198129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:38:42.820835Z","time spent":"377.218046ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:38:43.198185Z","caller":"traceutil/trace.go:171","msg":"trace[541133086] transaction","detail":"{read_only:false; response_revision:9120; number_of_response:1; }","duration":"377.192276ms","start":"2026-10-01T06:38:42.820965Z","end":"2026-10-01T06:38:43.198158Z","steps":["trace[541133086] 'process raft request' (duration: 376.904442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:38:43.198256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.1494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T06:38:43.198288Z","caller":"traceutil/trace.go:171","msg":"trace[1174516799] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:9120; }","duration":"207.225951ms","start":"2026-10-01T06:38:42.991053Z","end":"2026-10-01T06:38:43.198279Z","steps":["trace[1174516799] 'agreement among raft nodes before linearized reading' (duration: 207.081169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:38:43.198356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:38:42.820932Z","time spent":"377.319097ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:39:15.802085Z","caller":"traceutil/trace.go:171","msg":"trace[1203028131] linearizableReadLoop","detail":"{readStateIndex:9684; appliedIndex:9683; }","duration":"401.905352ms","start":"2026-10-01T06:39:15.400155Z","end":"2026-10-01T06:39:15.80206Z","steps":["trace[1203028131] 'read index received' (duration: 401.638839ms)","trace[1203028131] 'applied index is now lower than readState.Index' (duration: 264.613µs)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:39:15.802289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.112264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-01T06:39:15.802331Z","caller":"traceutil/trace.go:171","msg":"trace[2145582502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9347; }","duration":"402.211556ms","start":"2026-10-01T06:39:15.400108Z","end":"2026-10-01T06:39:15.802319Z","steps":["trace[2145582502] 'agreement among raft nodes before linearized reading' (duration: 402.056014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:39:15.802365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:39:15.400085Z","time spent":"402.271906ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-01T06:39:15.802322Z","caller":"traceutil/trace.go:171","msg":"trace[298497607] transaction","detail":"{read_only:false; response_revision:9347; number_of_response:1; }","duration":"413.654988ms","start":"2026-10-01T06:39:15.38849Z","end":"2026-10-01T06:39:15.802145Z","steps":["trace[298497607] 'process raft request' (duration: 413.402204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:39:15.803388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:39:15.388463Z","time spent":"414.729032ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:39:28.444385Z","caller":"traceutil/trace.go:171","msg":"trace[98419235] transaction","detail":"{read_only:false; response_revision:9415; number_of_response:1; }","duration":"260.976888ms","start":"2026-10-01T06:39:28.183381Z","end":"2026-10-01T06:39:28.444358Z","steps":["trace[98419235] 'process raft request' (duration: 239.374743ms)","trace[98419235] 'compare' (duration: 21.090768ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:39:28.444515Z","caller":"traceutil/trace.go:171","msg":"trace[383218614] linearizableReadLoop","detail":"{readStateIndex:9754; appliedIndex:9753; }","duration":"161.24471ms","start":"2026-10-01T06:39:28.283239Z","end":"2026-10-01T06:39:28.444484Z","steps":["trace[383218614] 'read index received' (duration: 139.543454ms)","trace[383218614] 'applied index is now lower than readState.Index' (duration: 21.698656ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:39:28.444701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.436622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T06:39:28.445446Z","caller":"traceutil/trace.go:171","msg":"trace[300084736] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:9416; }","duration":"161.573315ms","start":"2026-10-01T06:39:28.283176Z","end":"2026-10-01T06:39:28.444749Z","steps":["trace[300084736] 'agreement among raft nodes before linearized reading' (duration: 161.428433ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:39:30.670444Z","caller":"traceutil/trace.go:171","msg":"trace[435772320] transaction","detail":"{read_only:false; response_revision:9442; number_of_response:1; }","duration":"126.085225ms","start":"2026-10-01T06:39:30.544311Z","end":"2026-10-01T06:39:30.670396Z","steps":["trace[435772320] 'process raft request' (duration: 111.281359ms)","trace[435772320] 'compare' (duration: 14.538993ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:39:30.952133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.955071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T06:39:30.952271Z","caller":"traceutil/trace.go:171","msg":"trace[1459585646] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:9442; }","duration":"169.175614ms","start":"2026-10-01T06:39:30.783055Z","end":"2026-10-01T06:39:30.95223Z","steps":["trace[1459585646] 'count revisions from in-memory index tree' (duration: 168.807379ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:39:54.127292Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-01T06:39:54.133969Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-01T06:39:54.13412Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-01T06:40:51.51178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7793} {"level":"info","ts":"2026-10-01T06:40:51.628066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7793,"took":"113.664854ms","hash":4161011365,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":25538560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-01T06:40:51.628151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4161011365,"revision":7793,"compact-revision":4779} {"level":"info","ts":"2026-10-01T06:41:01.601336Z","caller":"traceutil/trace.go:171","msg":"trace[386462338] transaction","detail":"{read_only:false; response_revision:10299; number_of_response:1; }","duration":"127.207009ms","start":"2026-10-01T06:41:01.474101Z","end":"2026-10-01T06:41:01.601308Z","steps":["trace[386462338] 'process raft request' (duration: 127.045576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:41:01.807721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.959708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:41:01.807854Z","caller":"traceutil/trace.go:171","msg":"trace[1643282061] linearizableReadLoop","detail":"{readStateIndex:10667; appliedIndex:10666; }","duration":"100.913316ms","start":"2026-10-01T06:41:01.70692Z","end":"2026-10-01T06:41:01.807833Z","steps":["trace[1643282061] 'read index received' (duration: 17.44µs)","trace[1643282061] 'applied index is now lower than readState.Index' (duration: 100.894156ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:41:01.807886Z","caller":"traceutil/trace.go:171","msg":"trace[226642202] transaction","detail":"{read_only:false; response_revision:10300; number_of_response:1; }","duration":"168.974202ms","start":"2026-10-01T06:41:01.638868Z","end":"2026-10-01T06:41:01.807843Z","steps":["trace[226642202] 'process raft request' (duration: 63.799521ms)","trace[226642202] 'compare' (duration: 104.820236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:41:01.808074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.155769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-10-01T06:41:01.808132Z","caller":"traceutil/trace.go:171","msg":"trace[374386023] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10300; }","duration":"101.233509ms","start":"2026-10-01T06:41:01.706883Z","end":"2026-10-01T06:41:01.808116Z","steps":["trace[374386023] 'agreement among raft nodes before linearized reading' (duration: 101.046767ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:45:51.519398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10240} {"level":"info","ts":"2026-10-01T06:45:51.613096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10240,"took":"91.414902ms","hash":2486895140,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":25284608,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-01T06:45:51.613203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486895140,"revision":10240,"compact-revision":7793} {"level":"warn","ts":"2026-10-01T06:46:18.564578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.864432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:46:18.564686Z","caller":"traceutil/trace.go:171","msg":"trace[913433772] linearizableReadLoop","detail":"{readStateIndex:13052; appliedIndex:13051; }","duration":"285.403896ms","start":"2026-10-01T06:46:18.279265Z","end":"2026-10-01T06:46:18.564669Z","steps":["trace[913433772] 'read index received' (duration: 60.32826ms)","trace[913433772] 'applied index is now lower than readState.Index' (duration: 225.074306ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:46:18.564708Z","caller":"traceutil/trace.go:171","msg":"trace[1567718872] transaction","detail":"{read_only:false; response_revision:12611; number_of_response:1; }","duration":"358.190567ms","start":"2026-10-01T06:46:18.206484Z","end":"2026-10-01T06:46:18.564675Z","steps":["trace[1567718872] 'process raft request' (duration: 133.129081ms)","trace[1567718872] 'compare' (duration: 224.656628ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:46:18.564853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:46:18.206464Z","time spent":"358.31284ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:46:18.564874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.606601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-01T06:46:18.564904Z","caller":"traceutil/trace.go:171","msg":"trace[2040599144] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:12611; }","duration":"285.663492ms","start":"2026-10-01T06:46:18.279231Z","end":"2026-10-01T06:46:18.564894Z","steps":["trace[2040599144] 'agreement among raft nodes before linearized reading' (duration: 285.517029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:46:18.564936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.776886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-01T06:46:18.565049Z","caller":"traceutil/trace.go:171","msg":"trace[256668908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12611; }","duration":"210.98004ms","start":"2026-10-01T06:46:18.354046Z","end":"2026-10-01T06:46:18.565026Z","steps":["trace[256668908] 'agreement among raft nodes before linearized reading' (duration: 210.742855ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:46:39.613104Z","caller":"traceutil/trace.go:171","msg":"trace[1802571786] transaction","detail":"{read_only:false; response_revision:12727; number_of_response:1; }","duration":"163.999972ms","start":"2026-10-01T06:46:39.449072Z","end":"2026-10-01T06:46:39.613072Z","steps":["trace[1802571786] 'process raft request' (duration: 163.491671ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:46:40.834861Z","caller":"traceutil/trace.go:171","msg":"trace[2137151728] transaction","detail":"{read_only:false; response_revision:12734; number_of_response:1; }","duration":"147.71667ms","start":"2026-10-01T06:46:40.687115Z","end":"2026-10-01T06:46:40.834832Z","steps":["trace[2137151728] 'process raft request' (duration: 147.600139ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:46:41.035033Z","caller":"traceutil/trace.go:171","msg":"trace[1572156545] linearizableReadLoop","detail":"{readStateIndex:13181; appliedIndex:13179; }","duration":"277.677541ms","start":"2026-10-01T06:46:40.757297Z","end":"2026-10-01T06:46:41.034975Z","steps":["trace[1572156545] 'read index received' (duration: 77.354396ms)","trace[1572156545] 'applied index is now lower than readState.Index' (duration: 200.321655ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:46:41.035112Z","caller":"traceutil/trace.go:171","msg":"trace[1694683321] transaction","detail":"{read_only:false; response_revision:12735; number_of_response:1; }","duration":"346.404651ms","start":"2026-10-01T06:46:40.688676Z","end":"2026-10-01T06:46:41.035081Z","steps":["trace[1694683321] 'process raft request' (duration: 294.47344ms)","trace[1694683321] 'compare' (duration: 51.617504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:46:41.035288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:46:40.688663Z","time spent":"346.536514ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:46:41.035339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.016828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-01T06:46:41.035382Z","caller":"traceutil/trace.go:171","msg":"trace[612988284] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:12735; }","duration":"278.1087ms","start":"2026-10-01T06:46:40.757258Z","end":"2026-10-01T06:46:41.035367Z","steps":["trace[612988284] 'agreement among raft nodes before linearized reading' (duration: 277.924746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:46:41.035473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.59512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-01T06:46:41.035535Z","caller":"traceutil/trace.go:171","msg":"trace[1705341283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12735; }","duration":"141.699042ms","start":"2026-10-01T06:46:40.893821Z","end":"2026-10-01T06:46:41.035521Z","steps":["trace[1705341283] 'agreement among raft nodes before linearized reading' (duration: 141.526438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:46:41.035577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.690163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-01T06:46:41.035656Z","caller":"traceutil/trace.go:171","msg":"trace[1101805261] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:12735; }","duration":"113.812396ms","start":"2026-10-01T06:46:40.921826Z","end":"2026-10-01T06:46:41.035638Z","steps":["trace[1101805261] 'agreement among raft nodes before linearized reading' (duration: 113.614101ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:50:51.528685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12263} {"level":"info","ts":"2026-10-01T06:50:51.612897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12263,"took":"81.835392ms","hash":4093875465,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":27430912,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-01T06:50:51.612969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4093875465,"revision":12263,"compact-revision":10240} {"level":"info","ts":"2026-10-01T06:53:23.303982Z","caller":"traceutil/trace.go:171","msg":"trace[848391312] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"275.432913ms","start":"2026-10-01T06:53:23.028514Z","end":"2026-10-01T06:53:23.303946Z","steps":["trace[848391312] 'process raft request' (duration: 275.185809ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:53:23.501327Z","caller":"traceutil/trace.go:171","msg":"trace[1395699380] transaction","detail":"{read_only:false; response_revision:15419; number_of_response:1; }","duration":"162.770314ms","start":"2026-10-01T06:53:23.338528Z","end":"2026-10-01T06:53:23.501298Z","steps":["trace[1395699380] 'process raft request' (duration: 88.522947ms)","trace[1395699380] 'compare' (duration: 74.138165ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:53:25.783898Z","caller":"traceutil/trace.go:171","msg":"trace[1762599544] transaction","detail":"{read_only:false; response_revision:15439; number_of_response:1; }","duration":"145.963608ms","start":"2026-10-01T06:53:25.637906Z","end":"2026-10-01T06:53:25.78387Z","steps":["trace[1762599544] 'process raft request' (duration: 145.800466ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:53:25.792355Z","caller":"traceutil/trace.go:171","msg":"trace[1212680419] transaction","detail":"{read_only:false; response_revision:15440; number_of_response:1; }","duration":"105.97356ms","start":"2026-10-01T06:53:25.686359Z","end":"2026-10-01T06:53:25.792333Z","steps":["trace[1212680419] 'process raft request' (duration: 105.741516ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:53:34.877695Z","caller":"traceutil/trace.go:171","msg":"trace[2083172566] linearizableReadLoop","detail":"{readStateIndex:16141; appliedIndex:16140; }","duration":"110.253489ms","start":"2026-10-01T06:53:34.767411Z","end":"2026-10-01T06:53:34.877664Z","steps":["trace[2083172566] 'read index received' (duration: 109.966434ms)","trace[2083172566] 'applied index is now lower than readState.Index' (duration: 285.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:53:34.877762Z","caller":"traceutil/trace.go:171","msg":"trace[1985447388] transaction","detail":"{read_only:false; response_revision:15597; number_of_response:1; }","duration":"157.916729ms","start":"2026-10-01T06:53:34.719809Z","end":"2026-10-01T06:53:34.877725Z","steps":["trace[1985447388] 'process raft request' (duration: 157.656364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:53:34.878045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.577953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-176ed8d7-229c-4f1d-a005-046eb25114d1\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-01T06:53:34.878103Z","caller":"traceutil/trace.go:171","msg":"trace[147048060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-176ed8d7-229c-4f1d-a005-046eb25114d1; range_end:; response_count:1; response_revision:15597; }","duration":"110.735916ms","start":"2026-10-01T06:53:34.767349Z","end":"2026-10-01T06:53:34.878085Z","steps":["trace[147048060] 'agreement among raft nodes before linearized reading' (duration: 110.492723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:53:35.138942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.136458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:53:35.139277Z","caller":"traceutil/trace.go:171","msg":"trace[1583064755] transaction","detail":"{read_only:false; response_revision:15598; number_of_response:1; }","duration":"257.939488ms","start":"2026-10-01T06:53:34.881314Z","end":"2026-10-01T06:53:35.139253Z","steps":["trace[1583064755] 'process raft request' (duration: 52.397832ms)","trace[1583064755] 'compare' (duration: 205.003686ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:53:35.227742Z","caller":"traceutil/trace.go:171","msg":"trace[1537058658] linearizableReadLoop","detail":"{readStateIndex:16143; appliedIndex:16142; }","duration":"203.186225ms","start":"2026-10-01T06:53:35.024531Z","end":"2026-10-01T06:53:35.227717Z","steps":["trace[1537058658] 'read index received' (duration: 202.974341ms)","trace[1537058658] 'applied index is now lower than readState.Index' (duration: 210.974µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:53:35.227865Z","caller":"traceutil/trace.go:171","msg":"trace[1790816324] transaction","detail":"{read_only:false; response_revision:15599; number_of_response:1; }","duration":"341.411821ms","start":"2026-10-01T06:53:34.88641Z","end":"2026-10-01T06:53:35.227822Z","steps":["trace[1790816324] 'process raft request' (duration: 341.166807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:53:35.228048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.49877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.38\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-01T06:53:35.228098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:53:34.886383Z","time spent":"341.571802ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3708,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:53:35.228175Z","caller":"traceutil/trace.go:171","msg":"trace[506533856] range","detail":"{range_begin:/registry/masterleases/162.253.55.38; range_end:; response_count:1; response_revision:15599; }","duration":"203.636862ms","start":"2026-10-01T06:53:35.024518Z","end":"2026-10-01T06:53:35.228154Z","steps":["trace[506533856] 'agreement among raft nodes before linearized reading' (duration: 203.328958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:53:35.228023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.726423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-01T06:53:35.229204Z","caller":"traceutil/trace.go:171","msg":"trace[350425974] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:15599; }","duration":"158.959995ms","start":"2026-10-01T06:53:35.070221Z","end":"2026-10-01T06:53:35.22918Z","steps":["trace[350425974] 'agreement among raft nodes before linearized reading' (duration: 157.641852ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:55:24.692943Z","caller":"traceutil/trace.go:171","msg":"trace[431169920] transaction","detail":"{read_only:false; response_revision:16730; number_of_response:1; }","duration":"202.230243ms","start":"2026-10-01T06:55:24.490667Z","end":"2026-10-01T06:55:24.692897Z","steps":["trace[431169920] 'process raft request' (duration: 202.08639ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:55:24.825637Z","caller":"traceutil/trace.go:171","msg":"trace[128038231] transaction","detail":"{read_only:false; response_revision:16731; number_of_response:1; }","duration":"318.77083ms","start":"2026-10-01T06:55:24.506847Z","end":"2026-10-01T06:55:24.825618Z","steps":["trace[128038231] 'process raft request' (duration: 318.610767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:55:24.825776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:55:24.506819Z","time spent":"318.873182ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:55:24.826175Z","caller":"traceutil/trace.go:171","msg":"trace[812440206] transaction","detail":"{read_only:false; response_revision:16733; number_of_response:1; }","duration":"296.927773ms","start":"2026-10-01T06:55:24.529233Z","end":"2026-10-01T06:55:24.82616Z","steps":["trace[812440206] 'process raft request' (duration: 296.661288ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:55:24.826128Z","caller":"traceutil/trace.go:171","msg":"trace[555946024] linearizableReadLoop","detail":"{readStateIndex:17310; appliedIndex:17307; }","duration":"260.305499ms","start":"2026-10-01T06:55:24.565793Z","end":"2026-10-01T06:55:24.826099Z","steps":["trace[555946024] 'read index received' (duration: 127.750544ms)","trace[555946024] 'applied index is now lower than readState.Index' (duration: 132.552935ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:55:24.826283Z","caller":"traceutil/trace.go:171","msg":"trace[1657705872] transaction","detail":"{read_only:false; response_revision:16732; number_of_response:1; }","duration":"303.999462ms","start":"2026-10-01T06:55:24.522252Z","end":"2026-10-01T06:55:24.826251Z","steps":["trace[1657705872] 'process raft request' (duration: 303.324451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:55:24.826373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.543903ms","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-10-01T06:55:24.826426Z","caller":"traceutil/trace.go:171","msg":"trace[160226681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16733; }","duration":"260.657924ms","start":"2026-10-01T06:55:24.565755Z","end":"2026-10-01T06:55:24.826413Z","steps":["trace[160226681] 'agreement among raft nodes before linearized reading' (duration: 260.446711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:55:24.82643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:55:24.522238Z","time spent":"304.156545ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:55:24.826572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.805386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5451"} {"level":"info","ts":"2026-10-01T06:55:24.826614Z","caller":"traceutil/trace.go:171","msg":"trace[736483428] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16733; }","duration":"183.909097ms","start":"2026-10-01T06:55:24.642694Z","end":"2026-10-01T06:55:24.826603Z","steps":["trace[736483428] 'agreement among raft nodes before linearized reading' (duration: 183.767045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:55:24.827036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.614638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lzxpm\" ","response":"range_response_count:1 size:6442"} {"level":"info","ts":"2026-10-01T06:55:24.827078Z","caller":"traceutil/trace.go:171","msg":"trace[1889627315] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lzxpm; range_end:; response_count:1; response_revision:16733; }","duration":"178.705739ms","start":"2026-10-01T06:55:24.648363Z","end":"2026-10-01T06:55:24.827069Z","steps":["trace[1889627315] 'agreement among raft nodes before linearized reading' (duration: 178.575237ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:55:51.536603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14351} {"level":"info","ts":"2026-10-01T06:55:51.618865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14351,"took":"79.446872ms","hash":3945586537,"current-db-size-bytes":43520000,"current-db-size":"44 MB","current-db-size-in-use-bytes":30818304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-01T06:55:51.618947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3945586537,"revision":14351,"compact-revision":12263} {"level":"info","ts":"2026-10-01T07:00:51.54727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16872} {"level":"info","ts":"2026-10-01T07:00:51.667471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16872,"took":"115.551979ms","hash":2645209415,"current-db-size-bytes":45346816,"current-db-size":"45 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-01T07:00:51.667543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2645209415,"revision":16872,"compact-revision":14351} {"level":"warn","ts":"2026-10-01T07:00:57.727732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.460936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-01T07:00:57.727817Z","caller":"traceutil/trace.go:171","msg":"trace[725039536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19174; }","duration":"117.554287ms","start":"2026-10-01T07:00:57.610243Z","end":"2026-10-01T07:00:57.727797Z","steps":["trace[725039536] 'range keys from in-memory index tree' (duration: 117.312614ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:00:58.158643Z","caller":"traceutil/trace.go:171","msg":"trace[790735940] linearizableReadLoop","detail":"{readStateIndex:19835; appliedIndex:19834; }","duration":"113.770188ms","start":"2026-10-01T07:00:58.044852Z","end":"2026-10-01T07:00:58.158623Z","steps":["trace[790735940] 'read index received' (duration: 113.732197ms)","trace[790735940] 'applied index is now lower than readState.Index' (duration: 36.911µs)"],"step_count":2} {"level":"warn","ts":"2026-10-01T07:00:58.158814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.945021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29847300-89ksv\" ","response":"range_response_count:1 size:6959"} {"level":"info","ts":"2026-10-01T07:00:58.158764Z","caller":"traceutil/trace.go:171","msg":"trace[547118072] transaction","detail":"{read_only:false; response_revision:19176; number_of_response:1; }","duration":"143.561441ms","start":"2026-10-01T07:00:58.015171Z","end":"2026-10-01T07:00:58.158732Z","steps":["trace[547118072] 'process raft request' (duration: 143.327937ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:00:58.15886Z","caller":"traceutil/trace.go:171","msg":"trace[1365883263] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29847300-89ksv; range_end:; response_count:1; response_revision:19176; }","duration":"114.023543ms","start":"2026-10-01T07:00:58.044824Z","end":"2026-10-01T07:00:58.158847Z","steps":["trace[1365883263] 'agreement among raft nodes before linearized reading' (duration: 113.900731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.22662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.397628ms","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-10-01T07:00:58.226681Z","caller":"traceutil/trace.go:171","msg":"trace[49296178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19177; }","duration":"114.48989ms","start":"2026-10-01T07:00:58.112176Z","end":"2026-10-01T07:00:58.226666Z","steps":["trace[49296178] 'agreement among raft nodes before linearized reading' (duration: 114.335647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.226751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.289207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-01T07:00:58.226853Z","caller":"traceutil/trace.go:171","msg":"trace[491058481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19177; }","duration":"123.394128ms","start":"2026-10-01T07:00:58.103432Z","end":"2026-10-01T07:00:58.226826Z","steps":["trace[491058481] 'agreement among raft nodes before linearized reading' (duration: 123.174525ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:00:58.307318Z","caller":"traceutil/trace.go:171","msg":"trace[1885360547] transaction","detail":"{read_only:false; response_revision:19179; number_of_response:1; }","duration":"143.771354ms","start":"2026-10-01T07:00:58.163526Z","end":"2026-10-01T07:00:58.307297Z","steps":["trace[1885360547] 'process raft request' (duration: 143.432959ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:00:58.307513Z","caller":"traceutil/trace.go:171","msg":"trace[1309826017] transaction","detail":"{read_only:false; response_revision:19178; number_of_response:1; }","duration":"146.922554ms","start":"2026-10-01T07:00:58.160581Z","end":"2026-10-01T07:00:58.307503Z","steps":["trace[1309826017] 'process raft request' (duration: 146.269843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.307876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.653201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-01T07:00:58.3079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.515459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:948911"} {"level":"info","ts":"2026-10-01T07:00:58.30796Z","caller":"traceutil/trace.go:171","msg":"trace[1887193766] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:19179; }","duration":"141.605671ms","start":"2026-10-01T07:00:58.166342Z","end":"2026-10-01T07:00:58.307947Z","steps":["trace[1887193766] 'agreement among raft nodes before linearized reading' (duration: 140.710616ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:00:58.307965Z","caller":"traceutil/trace.go:171","msg":"trace[1791363869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19179; }","duration":"139.795343ms","start":"2026-10-01T07:00:58.168144Z","end":"2026-10-01T07:00:58.30794Z","steps":["trace[1791363869] 'agreement among raft nodes before linearized reading' (duration: 139.5736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.816405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11420179734054774998,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-01T07:00:58.847361Z","caller":"traceutil/trace.go:171","msg":"trace[888235298] transaction","detail":"{read_only:false; response_revision:19180; number_of_response:1; }","duration":"617.008791ms","start":"2026-10-01T07:00:58.230325Z","end":"2026-10-01T07:00:58.847334Z","steps":["trace[888235298] 'process raft request' (duration: 616.808818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.847509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:00:58.230308Z","time spent":"617.113513ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:00:58.847813Z","caller":"traceutil/trace.go:171","msg":"trace[380337659] transaction","detail":"{read_only:false; response_revision:19181; number_of_response:1; }","duration":"615.293315ms","start":"2026-10-01T07:00:58.232487Z","end":"2026-10-01T07:00:58.84778Z","steps":["trace[380337659] 'process raft request' (duration: 614.808298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.847953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:00:58.232475Z","time spent":"615.404647ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:00:58.847953Z","caller":"traceutil/trace.go:171","msg":"trace[671716625] transaction","detail":"{read_only:false; response_revision:19182; number_of_response:1; }","duration":"607.433393ms","start":"2026-10-01T07:00:58.240503Z","end":"2026-10-01T07:00:58.847936Z","steps":["trace[671716625] 'process raft request' (duration: 607.051837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.848076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:00:58.240485Z","time spent":"607.543034ms","remote":"127.0.0.1:46486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:00:58.897337Z","caller":"traceutil/trace.go:171","msg":"trace[1870150864] linearizableReadLoop","detail":"{readStateIndex:19842; appliedIndex:19839; }","duration":"581.777474ms","start":"2026-10-01T07:00:58.315536Z","end":"2026-10-01T07:00:58.897313Z","steps":["trace[1870150864] 'read index received' (duration: 531.746926ms)","trace[1870150864] 'applied index is now lower than readState.Index' (duration: 50.029478ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T07:00:58.89746Z","caller":"traceutil/trace.go:171","msg":"trace[587004627] transaction","detail":"{read_only:false; response_revision:19183; number_of_response:1; }","duration":"583.341728ms","start":"2026-10-01T07:00:58.314087Z","end":"2026-10-01T07:00:58.897429Z","steps":["trace[587004627] 'process raft request' (duration: 561.118782ms)","trace[587004627] 'compare' (duration: 21.963233ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T07:00:58.897607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:00:58.314069Z","time spent":"583.455959ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T07:00:58.89761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.959043ms","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-10-01T07:00:58.897732Z","caller":"traceutil/trace.go:171","msg":"trace[1154245108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19183; }","duration":"183.117626ms","start":"2026-10-01T07:00:58.714595Z","end":"2026-10-01T07:00:58.897713Z","steps":["trace[1154245108] 'agreement among raft nodes before linearized reading' (duration: 182.903453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.898764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.201756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:948911"} {"level":"info","ts":"2026-10-01T07:00:58.898852Z","caller":"traceutil/trace.go:171","msg":"trace[83876077] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:19183; }","duration":"583.334578ms","start":"2026-10-01T07:00:58.315505Z","end":"2026-10-01T07:00:58.898839Z","steps":["trace[83876077] 'agreement among raft nodes before linearized reading' (duration: 581.928536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:00:58.898938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:00:58.315494Z","time spent":"583.428269ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":97,"response size":948933,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-01T07:01:13.137796Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-01T07:01:13.146695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-01T07:01:13.146795Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-01T07:05:51.554939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19139} {"level":"info","ts":"2026-10-01T07:05:51.668257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19139,"took":"109.365325ms","hash":3906346222,"current-db-size-bytes":47190016,"current-db-size":"47 MB","current-db-size-in-use-bytes":34770944,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-01T07:05:51.668339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3906346222,"revision":19139,"compact-revision":16872} {"level":"warn","ts":"2026-10-01T07:08:16.373636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.620549ms","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:6"} {"level":"warn","ts":"2026-10-01T07:08:16.373679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.859548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-01T07:08:16.373767Z","caller":"traceutil/trace.go:171","msg":"trace[138255353] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:22496; }","duration":"149.815603ms","start":"2026-10-01T07:08:16.223928Z","end":"2026-10-01T07:08:16.373744Z","steps":["trace[138255353] 'count revisions from in-memory index tree' (duration: 149.511028ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:08:16.373775Z","caller":"traceutil/trace.go:171","msg":"trace[1695241827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22496; }","duration":"172.01737ms","start":"2026-10-01T07:08:16.201738Z","end":"2026-10-01T07:08:16.373755Z","steps":["trace[1695241827] 'range keys from in-memory index tree' (duration: 171.726296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:17.535883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.168421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-01T07:08:17.535978Z","caller":"traceutil/trace.go:171","msg":"trace[1000210945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22498; }","duration":"224.339663ms","start":"2026-10-01T07:08:17.311618Z","end":"2026-10-01T07:08:17.535958Z","steps":["trace[1000210945] 'range keys from in-memory index tree' (duration: 223.984478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:17.535971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.129155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-01T07:08:17.536101Z","caller":"traceutil/trace.go:171","msg":"trace[231950110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22498; }","duration":"223.312368ms","start":"2026-10-01T07:08:17.312755Z","end":"2026-10-01T07:08:17.536067Z","steps":["trace[231950110] 'range keys from in-memory index tree' (duration: 222.862241ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:08:17.64842Z","caller":"traceutil/trace.go:171","msg":"trace[208695550] transaction","detail":"{read_only:false; response_revision:22499; number_of_response:1; }","duration":"107.70697ms","start":"2026-10-01T07:08:17.540686Z","end":"2026-10-01T07:08:17.648393Z","steps":["trace[208695550] 'process raft request' (duration: 107.249693ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:08:17.992217Z","caller":"traceutil/trace.go:171","msg":"trace[572520252] transaction","detail":"{read_only:false; response_revision:22500; number_of_response:1; }","duration":"450.518094ms","start":"2026-10-01T07:08:17.541664Z","end":"2026-10-01T07:08:17.992182Z","steps":["trace[572520252] 'process raft request' (duration: 372.556132ms)","trace[572520252] 'compare' (duration: 77.191461ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T07:08:17.992361Z","caller":"traceutil/trace.go:171","msg":"trace[1331355454] linearizableReadLoop","detail":"{readStateIndex:23272; appliedIndex:23268; }","duration":"414.750426ms","start":"2026-10-01T07:08:17.577593Z","end":"2026-10-01T07:08:17.992344Z","steps":["trace[1331355454] 'read index received' (duration: 70.35467ms)","trace[1331355454] 'applied index is now lower than readState.Index' (duration: 344.390906ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T07:08:17.992399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:17.541651Z","time spent":"450.652265ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:08:17.992382Z","caller":"traceutil/trace.go:171","msg":"trace[667302346] transaction","detail":"{read_only:false; response_revision:22502; number_of_response:1; }","duration":"449.782013ms","start":"2026-10-01T07:08:17.542557Z","end":"2026-10-01T07:08:17.992339Z","steps":["trace[667302346] 'process raft request' (duration: 449.477488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:17.99255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.959669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-10-01T07:08:17.992514Z","caller":"traceutil/trace.go:171","msg":"trace[310766947] transaction","detail":"{read_only:false; response_revision:22501; number_of_response:1; }","duration":"450.605906ms","start":"2026-10-01T07:08:17.54184Z","end":"2026-10-01T07:08:17.992446Z","steps":["trace[310766947] 'process raft request' (duration: 450.067777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:17.992591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:17.542519Z","time spent":"449.955085ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:08:17.992591Z","caller":"traceutil/trace.go:171","msg":"trace[1543017035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22503; }","duration":"415.061521ms","start":"2026-10-01T07:08:17.577519Z","end":"2026-10-01T07:08:17.992581Z","steps":["trace[1543017035] 'agreement among raft nodes before linearized reading' (duration: 414.888008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:17.992815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:17.54181Z","time spent":"450.833419ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T07:08:17.992833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:17.577506Z","time spent":"415.315314ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-01T07:08:18.560168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.247691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-01T07:08:18.560574Z","caller":"traceutil/trace.go:171","msg":"trace[799970074] linearizableReadLoop","detail":"{readStateIndex:23276; appliedIndex:23274; }","duration":"392.596687ms","start":"2026-10-01T07:08:18.167953Z","end":"2026-10-01T07:08:18.560549Z","steps":["trace[799970074] 'read index received' (duration: 9.48066ms)","trace[799970074] 'applied index is now lower than readState.Index' (duration: 383.113197ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T07:08:18.560631Z","caller":"traceutil/trace.go:171","msg":"trace[22298843] transaction","detail":"{read_only:false; response_revision:22506; number_of_response:1; }","duration":"395.802225ms","start":"2026-10-01T07:08:18.164768Z","end":"2026-10-01T07:08:18.560571Z","steps":["trace[22298843] 'process raft request' (duration: 208.767373ms)","trace[22298843] 'compare' (duration: 185.939236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T07:08:18.560776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.79191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-01T07:08:18.561162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:18.164753Z","time spent":"396.286562ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T07:08:18.561187Z","caller":"traceutil/trace.go:171","msg":"trace[434832377] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22506; }","duration":"393.225536ms","start":"2026-10-01T07:08:18.167917Z","end":"2026-10-01T07:08:18.561143Z","steps":["trace[434832377] 'agreement among raft nodes before linearized reading' (duration: 392.755899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:18.561246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:18.167908Z","time spent":"393.321039ms","remote":"127.0.0.1:33570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-01T07:08:18.561446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.744774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-01T07:08:18.561491Z","caller":"traceutil/trace.go:171","msg":"trace[143193280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22506; }","duration":"359.820884ms","start":"2026-10-01T07:08:18.201656Z","end":"2026-10-01T07:08:18.561477Z","steps":["trace[143193280] 'agreement among raft nodes before linearized reading' (duration: 359.703863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T07:08:18.561544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T07:08:18.201646Z","time spent":"359.889905ms","remote":"127.0.0.1:33368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-01T07:08:18.561691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.83484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-01T07:08:18.561729Z","caller":"traceutil/trace.go:171","msg":"trace[1098813774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22506; }","duration":"136.910142ms","start":"2026-10-01T07:08:18.424804Z","end":"2026-10-01T07:08:18.561714Z","steps":["trace[1098813774] 'agreement among raft nodes before linearized reading' (duration: 136.741049ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T07:10:51.56605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21541} {"level":"info","ts":"2026-10-01T07:10:51.718658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21541,"took":"148.122476ms","hash":1056688088,"current-db-size-bytes":47190016,"current-db-size":"47 MB","current-db-size-in-use-bytes":27156480,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-01T07:10:51.718781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1056688088,"revision":21541,"compact-revision":19139} {"level":"info","ts":"2026-10-01T07:15:51.578275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23412} {"level":"info","ts":"2026-10-01T07:15:51.697478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23412,"took":"113.408496ms","hash":1812749090,"current-db-size-bytes":47190016,"current-db-size":"47 MB","current-db-size-in-use-bytes":25071616,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-01T07:15:51.697524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1812749090,"revision":23412,"compact-revision":21541}