{"level":"warn","ts":"2026-05-05T10:16:47.154715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:16:47.154837Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T10:16:47.154921Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:16:47.154938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-05T10:16:47.154958Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:16:47.155583Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-05T10:16:47.155661Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T10:16:47.159527Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.629345ms"} {"level":"info","ts":"2026-05-05T10:16:47.171567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-05T10:16:47.171699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:16:47.171728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:16:47.171754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:16:47.171766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:16:47.171821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-05T10:16:47.182355Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:16:47.184411Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:16:47.185867Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T10:16:47.188588Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:16:47.188845Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:16:47.189097Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:16:47.189202Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:16:47.189219Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:16:47.190026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-05T10:16:47.19017Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-05T10:16:47.194542Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:16:47.194697Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-05T10:16:47.194806Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-05T10:16:47.19485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:16:47.19491Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:16:47.472851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:16:47.472908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:16:47.472927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-05T10:16:47.472942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:16:47.472951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-05T10:16:47.472962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:16:47.472973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-05T10:16:47.474159Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:47.475188Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:16:47.475332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:16:47.475444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:16:47.476062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:16:47.4761Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:16:47.478043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T10:16:47.479702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-05T10:16:47.480011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:47.480107Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:47.480213Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:17:22.660092Z","caller":"traceutil/trace.go:171","msg":"trace[534570134] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"101.271287ms","start":"2026-05-05T10:17:22.558792Z","end":"2026-05-05T10:17:22.660064Z","steps":["trace[534570134] 'process raft request' (duration: 100.927849ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:28.984859Z","caller":"traceutil/trace.go:171","msg":"trace[605141663] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"169.974501ms","start":"2026-05-05T10:17:28.814844Z","end":"2026-05-05T10:17:28.984819Z","steps":["trace[605141663] 'read index received' (duration: 169.749955ms)","trace[605141663] 'applied index is now lower than readState.Index' (duration: 223.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:28.984927Z","caller":"traceutil/trace.go:171","msg":"trace[1042427963] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"176.647273ms","start":"2026-05-05T10:17:28.808221Z","end":"2026-05-05T10:17:28.984869Z","steps":["trace[1042427963] 'process raft request' (duration: 176.363416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:28.985116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.221987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:28.985281Z","caller":"traceutil/trace.go:171","msg":"trace[1824198350] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"170.432772ms","start":"2026-05-05T10:17:28.814827Z","end":"2026-05-05T10:17:28.98526Z","steps":["trace[1824198350] 'agreement among raft nodes before linearized reading' (duration: 170.174415ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.284196Z","caller":"traceutil/trace.go:171","msg":"trace[505568276] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"299.132867ms","start":"2026-05-05T10:17:28.984996Z","end":"2026-05-05T10:17:29.284129Z","steps":["trace[505568276] 'read index received' (duration: 295.083568ms)","trace[505568276] 'applied index is now lower than readState.Index' (duration: 4.047619ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:29.284424Z","caller":"traceutil/trace.go:171","msg":"trace[1293010601] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"401.577841ms","start":"2026-05-05T10:17:28.882825Z","end":"2026-05-05T10:17:29.284402Z","steps":["trace[1293010601] 'process raft request' (duration: 398.188539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.196979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:29.284524Z","caller":"traceutil/trace.go:171","msg":"trace[1993955944] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:625; }","duration":"469.296502ms","start":"2026-05-05T10:17:28.815196Z","end":"2026-05-05T10:17:29.284492Z","steps":["trace[1993955944] 'agreement among raft nodes before linearized reading' (duration: 469.078556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.677288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:29.284514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.008338ms","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-05-05T10:17:29.284493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.576299ms","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-05-05T10:17:29.284555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.067683ms","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":"warn","ts":"2026-05-05T10:17:29.284561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.815167Z","time spent":"469.383544ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:17:29.284569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.63338ms","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":"warn","ts":"2026-05-05T10:17:29.284577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.778478ms","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":"info","ts":"2026-05-05T10:17:29.284621Z","caller":"traceutil/trace.go:171","msg":"trace[27438378] 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:625; }","duration":"433.189352ms","start":"2026-05-05T10:17:28.851404Z","end":"2026-05-05T10:17:29.284593Z","steps":["trace[27438378] 'agreement among raft nodes before linearized reading' (duration: 432.993097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.313407ms","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-05-05T10:17:29.284591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.103823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:29.284681Z","caller":"traceutil/trace.go:171","msg":"trace[248217822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"407.783664ms","start":"2026-05-05T10:17:28.87684Z","end":"2026-05-05T10:17:29.284624Z","steps":["trace[248217822] 'agreement among raft nodes before linearized reading' (duration: 407.360684ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.28469Z","caller":"traceutil/trace.go:171","msg":"trace[805385659] 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:625; }","duration":"434.232797ms","start":"2026-05-05T10:17:28.85041Z","end":"2026-05-05T10:17:29.284643Z","steps":["trace[805385659] 'agreement among raft nodes before linearized reading' (duration: 434.030442ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.284696Z","caller":"traceutil/trace.go:171","msg":"trace[1316860171] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"390.643815ms","start":"2026-05-05T10:17:28.894039Z","end":"2026-05-05T10:17:29.284683Z","steps":["trace[1316860171] 'agreement among raft nodes before linearized reading' (duration: 390.180904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.85138Z","time spent":"433.307425ms","remote":"127.0.0.1:43312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-05-05T10:17:29.284715Z","caller":"traceutil/trace.go:171","msg":"trace[333592439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"407.821415ms","start":"2026-05-05T10:17:28.876842Z","end":"2026-05-05T10:17:29.284663Z","steps":["trace[333592439] 'agreement among raft nodes before linearized reading' (duration: 407.542458ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.28472Z","caller":"traceutil/trace.go:171","msg":"trace[1111757065] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"390.937682ms","start":"2026-05-05T10:17:28.893736Z","end":"2026-05-05T10:17:29.284674Z","steps":["trace[1111757065] 'agreement among raft nodes before linearized reading' (duration: 390.520672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.43932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:29.284752Z","caller":"traceutil/trace.go:171","msg":"trace[1980538455] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:625; }","duration":"452.301697ms","start":"2026-05-05T10:17:28.832409Z","end":"2026-05-05T10:17:29.284711Z","steps":["trace[1980538455] 'agreement among raft nodes before linearized reading' (duration: 452.00625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.894029Z","time spent":"390.717277ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-05T10:17:29.284749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.876787Z","time spent":"407.946767ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:17:29.284762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.850395Z","time spent":"434.34954ms","remote":"127.0.0.1:43312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:17:29.284728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.088187ms","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":"warn","ts":"2026-05-05T10:17:29.284778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.87683Z","time spent":"407.931917ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T10:17:29.284783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.893689Z","time spent":"391.074416ms","remote":"127.0.0.1:43328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:17:29.284773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.244226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:29.284808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.832392Z","time spent":"452.40156ms","remote":"127.0.0.1:43308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-05T10:17:29.284813Z","caller":"traceutil/trace.go:171","msg":"trace[1608735853] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:625; }","duration":"468.508622ms","start":"2026-05-05T10:17:28.816264Z","end":"2026-05-05T10:17:29.284772Z","steps":["trace[1608735853] 'agreement among raft nodes before linearized reading' (duration: 468.147954ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.28462Z","caller":"traceutil/trace.go:171","msg":"trace[761034002] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:625; }","duration":"450.877942ms","start":"2026-05-05T10:17:28.833695Z","end":"2026-05-05T10:17:29.284572Z","steps":["trace[761034002] 'agreement among raft nodes before linearized reading' (duration: 450.594826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.284902Z","caller":"traceutil/trace.go:171","msg":"trace[1976068103] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:625; }","duration":"452.377419ms","start":"2026-05-05T10:17:28.832461Z","end":"2026-05-05T10:17:29.284839Z","steps":["trace[1976068103] 'agreement among raft nodes before linearized reading' (duration: 452.258936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.816257Z","time spent":"468.652136ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-05T10:17:29.284532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.871358ms","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":"warn","ts":"2026-05-05T10:17:29.284961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.833673Z","time spent":"451.271642ms","remote":"127.0.0.1:43308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-05-05T10:17:29.284987Z","caller":"traceutil/trace.go:171","msg":"trace[995746245] 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:625; }","duration":"434.378071ms","start":"2026-05-05T10:17:28.850591Z","end":"2026-05-05T10:17:29.284969Z","steps":["trace[995746245] 'agreement among raft nodes before linearized reading' (duration: 433.815788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.284988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.832408Z","time spent":"452.564833ms","remote":"127.0.0.1:43308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:17:29.284909Z","caller":"traceutil/trace.go:171","msg":"trace[1961645692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"391.27448ms","start":"2026-05-05T10:17:28.893549Z","end":"2026-05-05T10:17:29.284823Z","steps":["trace[1961645692] 'agreement among raft nodes before linearized reading' (duration: 390.675716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.285072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.893532Z","time spent":"391.511977ms","remote":"127.0.0.1:43328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T10:17:29.285076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.850548Z","time spent":"434.472323ms","remote":"127.0.0.1:43312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T10:17:29.28509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:28.882797Z","time spent":"401.670864ms","remote":"127.0.0.1:47006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:29.81517Z","caller":"traceutil/trace.go:171","msg":"trace[195140618] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"358.928562ms","start":"2026-05-05T10:17:29.456199Z","end":"2026-05-05T10:17:29.815127Z","steps":["trace[195140618] 'read index received' (duration: 328.514473ms)","trace[195140618] 'applied index is now lower than readState.Index' (duration: 30.412789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:29.815449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.01494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T10:17:29.815457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.244902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T10:17:29.815556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.593997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T10:17:29.815608Z","caller":"traceutil/trace.go:171","msg":"trace[1612635021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"159.68912ms","start":"2026-05-05T10:17:29.655911Z","end":"2026-05-05T10:17:29.8156Z","steps":["trace[1612635021] 'agreement among raft nodes before linearized reading' (duration: 159.589917ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.815522Z","caller":"traceutil/trace.go:171","msg":"trace[1530126571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"359.354783ms","start":"2026-05-05T10:17:29.456151Z","end":"2026-05-05T10:17:29.815506Z","steps":["trace[1530126571] 'agreement among raft nodes before linearized reading' (duration: 359.131957ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:29.815695Z","caller":"traceutil/trace.go:171","msg":"trace[1917986997] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"457.936575ms","start":"2026-05-05T10:17:29.357727Z","end":"2026-05-05T10:17:29.815663Z","steps":["trace[1917986997] 'process raft request' (duration: 427.071554ms)","trace[1917986997] 'compare' (duration: 29.962519ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:29.815482Z","caller":"traceutil/trace.go:171","msg":"trace[988752269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"337.085722ms","start":"2026-05-05T10:17:29.478387Z","end":"2026-05-05T10:17:29.815472Z","steps":["trace[988752269] 'agreement among raft nodes before linearized reading' (duration: 336.892566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:29.81578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:29.478352Z","time spent":"337.41121ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T10:17:29.815842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:29.357697Z","time spent":"458.062108ms","remote":"127.0.0.1:47388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:17:29.815883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:29.456129Z","time spent":"359.749723ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:17:31.05419Z","caller":"traceutil/trace.go:171","msg":"trace[669140689] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"111.091846ms","start":"2026-05-05T10:17:30.943053Z","end":"2026-05-05T10:17:31.054145Z","steps":["trace[669140689] 'process raft request' (duration: 102.274232ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:37.378069Z","caller":"traceutil/trace.go:171","msg":"trace[549040421] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"160.117882ms","start":"2026-05-05T10:18:37.217924Z","end":"2026-05-05T10:18:37.378042Z","steps":["trace[549040421] 'process raft request' (duration: 127.60014ms)","trace[549040421] 'compare' (duration: 32.406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:00.734422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.007144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:19:00.734597Z","caller":"traceutil/trace.go:171","msg":"trace[1980242458] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"250.851264ms","start":"2026-05-05T10:19:00.483718Z","end":"2026-05-05T10:19:00.734569Z","steps":["trace[1980242458] 'process raft request' (duration: 70.304496ms)","trace[1980242458] 'compare' (duration: 179.8818ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:01.014346Z","caller":"traceutil/trace.go:171","msg":"trace[1178374175] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"201.010553ms","start":"2026-05-05T10:19:00.813316Z","end":"2026-05-05T10:19:01.014326Z","steps":["trace[1178374175] 'process raft request' (duration: 124.014172ms)","trace[1178374175] 'compare' (duration: 76.852037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:01.014524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.672755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:01.014337Z","caller":"traceutil/trace.go:171","msg":"trace[117458446] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"117.499231ms","start":"2026-05-05T10:19:00.896812Z","end":"2026-05-05T10:19:01.014312Z","steps":["trace[117458446] 'read index received' (duration: 40.422337ms)","trace[117458446] 'applied index is now lower than readState.Index' (duration: 77.075834ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:01.014586Z","caller":"traceutil/trace.go:171","msg":"trace[1275482742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1861; }","duration":"117.806228ms","start":"2026-05-05T10:19:00.896757Z","end":"2026-05-05T10:19:01.014563Z","steps":["trace[1275482742] 'agreement among raft nodes before linearized reading' (duration: 117.627833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:44.620362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.816581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:19:44.62062Z","caller":"traceutil/trace.go:171","msg":"trace[1963365952] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"239.364347ms","start":"2026-05-05T10:19:44.381212Z","end":"2026-05-05T10:19:44.620576Z","steps":["trace[1963365952] 'process raft request' (duration: 58.246408ms)","trace[1963365952] 'compare' (duration: 180.712999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:50.954991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.798962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:50.955095Z","caller":"traceutil/trace.go:171","msg":"trace[66962075] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2187; }","duration":"118.971556ms","start":"2026-05-05T10:19:50.836102Z","end":"2026-05-05T10:19:50.955074Z","steps":["trace[66962075] 'count revisions from in-memory index tree' (duration: 118.68321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:50.955238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.139895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T10:19:50.955336Z","caller":"traceutil/trace.go:171","msg":"trace[804731316] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:2187; }","duration":"149.285289ms","start":"2026-05-05T10:19:50.806028Z","end":"2026-05-05T10:19:50.955313Z","steps":["trace[804731316] 'range keys from in-memory index tree' (duration: 148.918459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:51.677116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.891195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T10:19:51.677216Z","caller":"traceutil/trace.go:171","msg":"trace[1917874540] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"268.04754ms","start":"2026-05-05T10:19:51.40915Z","end":"2026-05-05T10:19:51.677197Z","steps":["trace[1917874540] 'range keys from in-memory index tree' (duration: 267.647429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:24.621972Z","caller":"traceutil/trace.go:171","msg":"trace[1278105010] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"148.328617ms","start":"2026-05-05T10:21:24.473617Z","end":"2026-05-05T10:21:24.621945Z","steps":["trace[1278105010] 'process raft request' (duration: 148.170404ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:25.162359Z","caller":"traceutil/trace.go:171","msg":"trace[1966528908] linearizableReadLoop","detail":"{readStateIndex:2993; appliedIndex:2992; }","duration":"111.597025ms","start":"2026-05-05T10:21:25.050737Z","end":"2026-05-05T10:21:25.162334Z","steps":["trace[1966528908] 'read index received' (duration: 109.898784ms)","trace[1966528908] 'applied index is now lower than readState.Index' (duration: 1.697091ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:25.162436Z","caller":"traceutil/trace.go:171","msg":"trace[477567588] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"164.430541ms","start":"2026-05-05T10:21:24.997979Z","end":"2026-05-05T10:21:25.162409Z","steps":["trace[477567588] 'process raft request' (duration: 162.641749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:25.162677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.921112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-05T10:21:25.162745Z","caller":"traceutil/trace.go:171","msg":"trace[2038477387] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2888; }","duration":"112.030634ms","start":"2026-05-05T10:21:25.050699Z","end":"2026-05-05T10:21:25.162729Z","steps":["trace[2038477387] 'agreement among raft nodes before linearized reading' (duration: 111.810389ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:29.979337Z","caller":"traceutil/trace.go:171","msg":"trace[164147950] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"130.109601ms","start":"2026-05-05T10:25:29.849178Z","end":"2026-05-05T10:25:29.979288Z","steps":["trace[164147950] 'process raft request' (duration: 129.742832ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:29.979877Z","caller":"traceutil/trace.go:171","msg":"trace[1241181768] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5186; }","duration":"114.541062ms","start":"2026-05-05T10:25:29.865303Z","end":"2026-05-05T10:25:29.979844Z","steps":["trace[1241181768] 'read index received' (duration: 48.313347ms)","trace[1241181768] 'applied index is now lower than readState.Index' (duration: 66.226445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:25:29.980424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.096395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:25:29.980476Z","caller":"traceutil/trace.go:171","msg":"trace[178360305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5001; }","duration":"115.207578ms","start":"2026-05-05T10:25:29.865257Z","end":"2026-05-05T10:25:29.980464Z","steps":["trace[178360305] 'agreement among raft nodes before linearized reading' (duration: 115.040664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:30.971461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.119845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T10:25:30.971576Z","caller":"traceutil/trace.go:171","msg":"trace[498176552] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:5098; }","duration":"142.293008ms","start":"2026-05-05T10:25:30.829259Z","end":"2026-05-05T10:25:30.971552Z","steps":["trace[498176552] 'range keys from in-memory index tree' (duration: 141.905319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:30.972104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.90772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-05-05T10:25:30.972141Z","caller":"traceutil/trace.go:171","msg":"trace[1275772593] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5098; }","duration":"125.984101ms","start":"2026-05-05T10:25:30.846148Z","end":"2026-05-05T10:25:30.972132Z","steps":["trace[1275772593] 'range keys from in-memory index tree' (duration: 125.770676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:30.972359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.832898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-05-05T10:25:30.972395Z","caller":"traceutil/trace.go:171","msg":"trace[1937409836] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5098; }","duration":"125.887048ms","start":"2026-05-05T10:25:30.846498Z","end":"2026-05-05T10:25:30.972385Z","steps":["trace[1937409836] 'range keys from in-memory index tree' (duration: 125.750486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:30.972575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.876679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-05-05T10:25:30.972605Z","caller":"traceutil/trace.go:171","msg":"trace[138541756] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5098; }","duration":"125.92281ms","start":"2026-05-05T10:25:30.846674Z","end":"2026-05-05T10:25:30.972596Z","steps":["trace[138541756] 'range keys from in-memory index tree' (duration: 125.795667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.718273Z","caller":"traceutil/trace.go:171","msg":"trace[784286589] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5316; }","duration":"119.847662ms","start":"2026-05-05T10:25:32.598401Z","end":"2026-05-05T10:25:32.718249Z","steps":["trace[784286589] 'read index received' (duration: 119.614836ms)","trace[784286589] 'applied index is now lower than readState.Index' (duration: 231.696µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:25:32.71853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.101378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T10:25:32.71853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.316474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T10:25:32.718574Z","caller":"traceutil/trace.go:171","msg":"trace[823221469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5126; }","duration":"120.18045ms","start":"2026-05-05T10:25:32.598381Z","end":"2026-05-05T10:25:32.718562Z","steps":["trace[823221469] 'agreement among raft nodes before linearized reading' (duration: 120.025546ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.718584Z","caller":"traceutil/trace.go:171","msg":"trace[1145833470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5126; }","duration":"118.400716ms","start":"2026-05-05T10:25:32.60017Z","end":"2026-05-05T10:25:32.71857Z","steps":["trace[1145833470] 'agreement among raft nodes before linearized reading' (duration: 118.240252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:32.718615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.132223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bnjn4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T10:25:32.718678Z","caller":"traceutil/trace.go:171","msg":"trace[1214788493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bnjn4; range_end:; response_count:1; response_revision:5126; }","duration":"114.212875ms","start":"2026-05-05T10:25:32.604452Z","end":"2026-05-05T10:25:32.718665Z","steps":["trace[1214788493] 'agreement among raft nodes before linearized reading' (duration: 114.00478ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.718365Z","caller":"traceutil/trace.go:171","msg":"trace[2033732184] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"121.838849ms","start":"2026-05-05T10:25:32.596478Z","end":"2026-05-05T10:25:32.718317Z","steps":["trace[2033732184] 'process raft request' (duration: 121.614554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.861787Z","caller":"traceutil/trace.go:171","msg":"trace[751689151] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"138.454275ms","start":"2026-05-05T10:25:32.723309Z","end":"2026-05-05T10:25:32.861763Z","steps":["trace[751689151] 'process raft request' (duration: 138.291441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.914084Z","caller":"traceutil/trace.go:171","msg":"trace[1765950547] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5317; }","duration":"189.756156ms","start":"2026-05-05T10:25:32.724299Z","end":"2026-05-05T10:25:32.914055Z","steps":["trace[1765950547] 'read index received' (duration: 137.376889ms)","trace[1765950547] 'applied index is now lower than readState.Index' (duration: 52.377927ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:25:32.914245Z","caller":"traceutil/trace.go:171","msg":"trace[66413146] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"190.862263ms","start":"2026-05-05T10:25:32.723322Z","end":"2026-05-05T10:25:32.914185Z","steps":["trace[66413146] 'process raft request' (duration: 190.592557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:32.914376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.563136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-05T10:25:32.914435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.130195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62983"} {"level":"info","ts":"2026-05-05T10:25:32.914482Z","caller":"traceutil/trace.go:171","msg":"trace[698498870] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5128; }","duration":"190.207777ms","start":"2026-05-05T10:25:32.724262Z","end":"2026-05-05T10:25:32.91447Z","steps":["trace[698498870] 'agreement among raft nodes before linearized reading' (duration: 189.936131ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:32.914433Z","caller":"traceutil/trace.go:171","msg":"trace[1789746870] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5128; }","duration":"188.654389ms","start":"2026-05-05T10:25:32.725762Z","end":"2026-05-05T10:25:32.914416Z","steps":["trace[1789746870] 'agreement among raft nodes before linearized reading' (duration: 188.484275ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:44.20598Z","caller":"traceutil/trace.go:171","msg":"trace[790893546] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"112.221086ms","start":"2026-05-05T10:25:44.093699Z","end":"2026-05-05T10:25:44.20592Z","steps":["trace[790893546] 'process raft request' (duration: 111.945489ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:44.71699Z","caller":"traceutil/trace.go:171","msg":"trace[2079249208] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"124.354521ms","start":"2026-05-05T10:25:44.592577Z","end":"2026-05-05T10:25:44.716931Z","steps":["trace[2079249208] 'process raft request' (duration: 124.235068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:44.716993Z","caller":"traceutil/trace.go:171","msg":"trace[483235260] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"125.433619ms","start":"2026-05-05T10:25:44.591529Z","end":"2026-05-05T10:25:44.716963Z","steps":["trace[483235260] 'process raft request' (duration: 125.198413ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:44.716858Z","caller":"traceutil/trace.go:171","msg":"trace[475848998] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"125.463638ms","start":"2026-05-05T10:25:44.591363Z","end":"2026-05-05T10:25:44.716827Z","steps":["trace[475848998] 'process raft request' (duration: 83.060645ms)","trace[475848998] 'compare' (duration: 42.118007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:25:53.464118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.565835ms","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-05-05T10:25:53.464468Z","caller":"traceutil/trace.go:171","msg":"trace[1639173663] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5539; }","duration":"118.011335ms","start":"2026-05-05T10:25:53.34643Z","end":"2026-05-05T10:25:53.464442Z","steps":["trace[1639173663] 'range keys from in-memory index tree' (duration: 117.440342ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:53.685725Z","caller":"traceutil/trace.go:171","msg":"trace[535690523] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"145.973677ms","start":"2026-05-05T10:25:53.539711Z","end":"2026-05-05T10:25:53.685685Z","steps":["trace[535690523] 'process raft request' (duration: 145.725781ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:25:53.88177Z","caller":"traceutil/trace.go:171","msg":"trace[797715765] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"224.845691ms","start":"2026-05-05T10:25:53.656884Z","end":"2026-05-05T10:25:53.881729Z","steps":["trace[797715765] 'process raft request' (duration: 133.376581ms)","trace[797715765] 'compare' (duration: 91.333097ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:25:53.943539Z","caller":"traceutil/trace.go:171","msg":"trace[1514928562] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5743; }","duration":"244.604321ms","start":"2026-05-05T10:25:53.698907Z","end":"2026-05-05T10:25:53.943512Z","steps":["trace[1514928562] 'read index received' (duration: 91.379057ms)","trace[1514928562] 'applied index is now lower than readState.Index' (duration: 153.224114ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:25:53.943583Z","caller":"traceutil/trace.go:171","msg":"trace[1137020746] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"250.23764ms","start":"2026-05-05T10:25:53.69332Z","end":"2026-05-05T10:25:53.943558Z","steps":["trace[1137020746] 'process raft request' (duration: 250.041915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:53.943828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.886739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T10:25:53.943923Z","caller":"traceutil/trace.go:171","msg":"trace[1094905970] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5542; }","duration":"245.001062ms","start":"2026-05-05T10:25:53.6989Z","end":"2026-05-05T10:25:53.943901Z","steps":["trace[1094905970] 'agreement among raft nodes before linearized reading' (duration: 244.720395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:53.958213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.361054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:25:53.958321Z","caller":"traceutil/trace.go:171","msg":"trace[820984184] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5543; }","duration":"182.617221ms","start":"2026-05-05T10:25:53.775682Z","end":"2026-05-05T10:25:53.958299Z","steps":["trace[820984184] 'agreement among raft nodes before linearized reading' (duration: 182.340044ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:26:47.767497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-05-05T10:26:47.858699Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"89.43153ms","hash":4239383504,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T10:26:47.858873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4239383504,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-05-05T10:31:47.782164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6052} {"level":"info","ts":"2026-05-05T10:31:47.875712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6052,"took":"92.188827ms","hash":2510633824,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":21893120,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-05T10:31:47.875786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510633824,"revision":6052,"compact-revision":3015} {"level":"warn","ts":"2026-05-05T10:32:01.438173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.575405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T10:32:01.438184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.2353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bnjn4\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-05-05T10:32:01.438246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.714561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T10:32:01.438292Z","caller":"traceutil/trace.go:171","msg":"trace[497668254] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bnjn4; range_end:; response_count:1; response_revision:8368; }","duration":"155.408983ms","start":"2026-05-05T10:32:01.282855Z","end":"2026-05-05T10:32:01.438264Z","steps":["trace[497668254] 'range keys from in-memory index tree' (duration: 154.958953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:01.438256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.439335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T10:32:01.438345Z","caller":"traceutil/trace.go:171","msg":"trace[1988081840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8368; }","duration":"443.864094ms","start":"2026-05-05T10:32:00.994459Z","end":"2026-05-05T10:32:01.438323Z","steps":["trace[1988081840] 'range keys from in-memory index tree' (duration: 443.517646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:01.438394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:32:00.994442Z","time spent":"443.942026ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T10:32:01.438384Z","caller":"traceutil/trace.go:171","msg":"trace[1993054452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8368; }","duration":"359.633961ms","start":"2026-05-05T10:32:01.078722Z","end":"2026-05-05T10:32:01.438355Z","steps":["trace[1993054452] 'range keys from in-memory index tree' (duration: 359.20687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:01.438495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.385437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-05T10:32:01.438544Z","caller":"traceutil/trace.go:171","msg":"trace[1384145974] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8368; }","duration":"365.494729ms","start":"2026-05-05T10:32:01.073034Z","end":"2026-05-05T10:32:01.438529Z","steps":["trace[1384145974] 'range keys from in-memory index tree' (duration: 365.215572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:01.438527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:32:01.078708Z","time spent":"359.792334ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:32:01.438595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:32:01.072997Z","time spent":"365.587281ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T10:32:01.438554Z","caller":"traceutil/trace.go:171","msg":"trace[537394233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8368; }","duration":"357.730748ms","start":"2026-05-05T10:32:01.080523Z","end":"2026-05-05T10:32:01.438254Z","steps":["trace[537394233] 'range keys from in-memory index tree' (duration: 357.386701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:01.438955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:32:01.080502Z","time spent":"358.414554ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:32:01.482683Z","caller":"traceutil/trace.go:171","msg":"trace[1686209662] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"164.992742ms","start":"2026-05-05T10:32:01.317654Z","end":"2026-05-05T10:32:01.482647Z","steps":["trace[1686209662] 'process raft request' (duration: 164.804958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:13.080811Z","caller":"traceutil/trace.go:171","msg":"trace[850511579] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"136.373309ms","start":"2026-05-05T10:32:12.944407Z","end":"2026-05-05T10:32:13.080781Z","steps":["trace[850511579] 'process raft request' (duration: 136.097083ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:13.974192Z","caller":"traceutil/trace.go:171","msg":"trace[1334190289] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"149.7795ms","start":"2026-05-05T10:32:13.82438Z","end":"2026-05-05T10:32:13.97416Z","steps":["trace[1334190289] 'process raft request' (duration: 149.582316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:14.054705Z","caller":"traceutil/trace.go:171","msg":"trace[90110896] linearizableReadLoop","detail":"{readStateIndex:8731; appliedIndex:8729; }","duration":"159.80233ms","start":"2026-05-05T10:32:13.894884Z","end":"2026-05-05T10:32:14.054686Z","steps":["trace[90110896] 'read index received' (duration: 79.080472ms)","trace[90110896] 'applied index is now lower than readState.Index' (duration: 80.721158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:32:14.054819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.925582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:32:14.054842Z","caller":"traceutil/trace.go:171","msg":"trace[978793639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8436; }","duration":"159.994774ms","start":"2026-05-05T10:32:13.89484Z","end":"2026-05-05T10:32:14.054835Z","steps":["trace[978793639] 'agreement among raft nodes before linearized reading' (duration: 159.920752ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:14.054828Z","caller":"traceutil/trace.go:171","msg":"trace[1570013217] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"201.966957ms","start":"2026-05-05T10:32:13.852825Z","end":"2026-05-05T10:32:14.054792Z","steps":["trace[1570013217] 'process raft request' (duration: 183.470865ms)","trace[1570013217] 'compare' (duration: 18.250177ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:32:42.919693Z","caller":"traceutil/trace.go:171","msg":"trace[727211425] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"174.902518ms","start":"2026-05-05T10:32:42.744769Z","end":"2026-05-05T10:32:42.919671Z","steps":["trace[727211425] 'process raft request' (duration: 174.775115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:56.012235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.309127ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-05T10:32:56.012549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.854153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:32:56.012636Z","caller":"traceutil/trace.go:171","msg":"trace[1652960779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8755; }","duration":"115.990156ms","start":"2026-05-05T10:32:55.896625Z","end":"2026-05-05T10:32:56.012616Z","steps":["trace[1652960779] 'agreement among raft nodes before linearized reading' (duration: 115.816362ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.012348Z","caller":"traceutil/trace.go:171","msg":"trace[896840399] linearizableReadLoop","detail":"{readStateIndex:9064; appliedIndex:9063; }","duration":"115.652999ms","start":"2026-05-05T10:32:55.896677Z","end":"2026-05-05T10:32:56.01233Z","steps":["trace[896840399] 'read index received' (duration: 2.27222ms)","trace[896840399] 'applied index is now lower than readState.Index' (duration: 113.379299ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:32:56.129969Z","caller":"traceutil/trace.go:171","msg":"trace[1235334470] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9064; }","duration":"108.152926ms","start":"2026-05-05T10:32:56.021789Z","end":"2026-05-05T10:32:56.129942Z","steps":["trace[1235334470] 'read index received' (duration: 107.983922ms)","trace[1235334470] 'applied index is now lower than readState.Index' (duration: 168.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:32:56.130037Z","caller":"traceutil/trace.go:171","msg":"trace[845992468] transaction","detail":"{read_only:false; response_revision:8756; number_of_response:1; }","duration":"108.683707ms","start":"2026-05-05T10:32:56.021328Z","end":"2026-05-05T10:32:56.130011Z","steps":["trace[845992468] 'process raft request' (duration: 108.383271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:32:56.130573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.767089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228793"} {"level":"info","ts":"2026-05-05T10:32:56.13064Z","caller":"traceutil/trace.go:171","msg":"trace[1372399590] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8756; }","duration":"108.896601ms","start":"2026-05-05T10:32:56.021728Z","end":"2026-05-05T10:32:56.130625Z","steps":["trace[1372399590] 'agreement among raft nodes before linearized reading' (duration: 108.323289ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.142918Z","caller":"traceutil/trace.go:171","msg":"trace[1157453935] transaction","detail":"{read_only:false; response_revision:8757; number_of_response:1; }","duration":"120.880182ms","start":"2026-05-05T10:32:56.022011Z","end":"2026-05-05T10:32:56.142891Z","steps":["trace[1157453935] 'process raft request' (duration: 120.701538ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.143217Z","caller":"traceutil/trace.go:171","msg":"trace[1787907098] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"121.158528ms","start":"2026-05-05T10:32:56.022018Z","end":"2026-05-05T10:32:56.143177Z","steps":["trace[1787907098] 'process raft request' (duration: 120.833141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.361649Z","caller":"traceutil/trace.go:171","msg":"trace[1459911743] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"171.80968ms","start":"2026-05-05T10:32:56.189793Z","end":"2026-05-05T10:32:56.361603Z","steps":["trace[1459911743] 'process raft request' (duration: 171.570755ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.377553Z","caller":"traceutil/trace.go:171","msg":"trace[1176097378] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"178.704982ms","start":"2026-05-05T10:32:56.198805Z","end":"2026-05-05T10:32:56.37751Z","steps":["trace[1176097378] 'process raft request' (duration: 178.475657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:56.377765Z","caller":"traceutil/trace.go:171","msg":"trace[59988923] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"111.746833ms","start":"2026-05-05T10:32:56.265994Z","end":"2026-05-05T10:32:56.377741Z","steps":["trace[59988923] 'process raft request' (duration: 111.426196ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:32:57.209535Z","caller":"traceutil/trace.go:171","msg":"trace[1903876530] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"146.584432ms","start":"2026-05-05T10:32:57.06293Z","end":"2026-05-05T10:32:57.209514Z","steps":["trace[1903876530] 'process raft request' (duration: 146.424178ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:33:13.829827Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242e.wal"} {"level":"info","ts":"2026-05-05T10:34:15.246764Z","caller":"traceutil/trace.go:171","msg":"trace[539653495] transaction","detail":"{read_only:false; response_revision:9521; number_of_response:1; }","duration":"154.1663ms","start":"2026-05-05T10:34:15.092572Z","end":"2026-05-05T10:34:15.246738Z","steps":["trace[539653495] 'process raft request' (duration: 154.000776ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:34:32.683958Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T10:34:32.699557Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T10:34:32.699664Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T10:36:47.787636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8162} {"level":"info","ts":"2026-05-05T10:36:47.84963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8162,"took":"59.838628ms","hash":1828813801,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":29241344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T10:36:47.849685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1828813801,"revision":8162,"compact-revision":6052} {"level":"warn","ts":"2026-05-05T10:37:38.425885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.011792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:37:38.425993Z","caller":"traceutil/trace.go:171","msg":"trace[295943822] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:11255; }","duration":"123.173545ms","start":"2026-05-05T10:37:38.302802Z","end":"2026-05-05T10:37:38.425975Z","steps":["trace[295943822] 'count revisions from in-memory index tree' (duration: 122.914819ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:37:56.279648Z","caller":"traceutil/trace.go:171","msg":"trace[399524296] linearizableReadLoop","detail":"{readStateIndex:11736; appliedIndex:11735; }","duration":"116.403682ms","start":"2026-05-05T10:37:56.163218Z","end":"2026-05-05T10:37:56.279622Z","steps":["trace[399524296] 'read index received' (duration: 116.252449ms)","trace[399524296] 'applied index is now lower than readState.Index' (duration: 150.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:37:56.279763Z","caller":"traceutil/trace.go:171","msg":"trace[2044426056] transaction","detail":"{read_only:false; response_revision:11353; number_of_response:1; }","duration":"148.485647ms","start":"2026-05-05T10:37:56.13125Z","end":"2026-05-05T10:37:56.279736Z","steps":["trace[2044426056] 'process raft request' (duration: 148.181081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:37:56.280039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.667898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bnjn4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T10:37:56.280104Z","caller":"traceutil/trace.go:171","msg":"trace[1759526672] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bnjn4; range_end:; response_count:1; response_revision:11353; }","duration":"116.924643ms","start":"2026-05-05T10:37:56.163163Z","end":"2026-05-05T10:37:56.280088Z","steps":["trace[1759526672] 'agreement among raft nodes before linearized reading' (duration: 116.596115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:41:47.794353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10684} {"level":"info","ts":"2026-05-05T10:41:47.876455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10684,"took":"79.737018ms","hash":1908006620,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":27615232,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T10:41:47.876512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1908006620,"revision":10684,"compact-revision":8162} {"level":"info","ts":"2026-05-05T10:46:47.804161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12850} {"level":"info","ts":"2026-05-05T10:46:47.882792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12850,"took":"76.207439ms","hash":3727581154,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":24956928,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-05T10:46:47.882853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3727581154,"revision":12850,"compact-revision":10684} {"level":"info","ts":"2026-05-05T10:51:47.810617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14674} {"level":"info","ts":"2026-05-05T10:51:47.890965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14674,"took":"77.503534ms","hash":3030962660,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":21106688,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T10:51:47.891039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3030962660,"revision":14674,"compact-revision":12850} {"level":"info","ts":"2026-05-05T10:56:47.818241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16196} {"level":"info","ts":"2026-05-05T10:56:47.895834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16196,"took":"74.497975ms","hash":1169747372,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":20541440,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T10:56:47.895893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1169747372,"revision":16196,"compact-revision":14674} {"level":"info","ts":"2026-05-05T11:00:41.807664Z","caller":"traceutil/trace.go:171","msg":"trace[1201137908] transaction","detail":"{read_only:false; response_revision:18908; number_of_response:1; }","duration":"122.948262ms","start":"2026-05-05T11:00:41.684675Z","end":"2026-05-05T11:00:41.807623Z","steps":["trace[1201137908] 'process raft request' (duration: 122.707766ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:00:49.358574Z","caller":"traceutil/trace.go:171","msg":"trace[942359098] transaction","detail":"{read_only:false; response_revision:18944; number_of_response:1; }","duration":"185.118035ms","start":"2026-05-05T11:00:49.173425Z","end":"2026-05-05T11:00:49.358543Z","steps":["trace[942359098] 'process raft request' (duration: 109.951382ms)","trace[942359098] 'compare' (duration: 75.030199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:00:54.397227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.316102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T11:00:54.397314Z","caller":"traceutil/trace.go:171","msg":"trace[240197563] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:18969; }","duration":"134.454706ms","start":"2026-05-05T11:00:54.262838Z","end":"2026-05-05T11:00:54.397292Z","steps":["trace[240197563] 'count revisions from in-memory index tree' (duration: 134.19799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:00:54.946492Z","caller":"traceutil/trace.go:171","msg":"trace[1170721848] transaction","detail":"{read_only:false; response_revision:18974; number_of_response:1; }","duration":"104.378648ms","start":"2026-05-05T11:00:54.842082Z","end":"2026-05-05T11:00:54.94646Z","steps":["trace[1170721848] 'process raft request' (duration: 35.845654ms)","trace[1170721848] 'compare' (duration: 68.3835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:00:55.24428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.085974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T11:00:55.244414Z","caller":"traceutil/trace.go:171","msg":"trace[1225349190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18974; }","duration":"185.278469ms","start":"2026-05-05T11:00:55.059115Z","end":"2026-05-05T11:00:55.244394Z","steps":["trace[1225349190] 'range keys from in-memory index tree' (duration: 184.847047ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:00:55.376919Z","caller":"traceutil/trace.go:171","msg":"trace[322796101] linearizableReadLoop","detail":"{readStateIndex:19660; appliedIndex:19659; }","duration":"121.971198ms","start":"2026-05-05T11:00:55.25492Z","end":"2026-05-05T11:00:55.376891Z","steps":["trace[322796101] 'read index received' (duration: 121.828045ms)","trace[322796101] 'applied index is now lower than readState.Index' (duration: 141.443µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:00:55.376978Z","caller":"traceutil/trace.go:171","msg":"trace[1770558408] transaction","detail":"{read_only:false; response_revision:18975; number_of_response:1; }","duration":"127.593113ms","start":"2026-05-05T11:00:55.249349Z","end":"2026-05-05T11:00:55.376942Z","steps":["trace[1770558408] 'process raft request' (duration: 127.351477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:00:55.377145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.212254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T11:00:55.37719Z","caller":"traceutil/trace.go:171","msg":"trace[1469516740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18975; }","duration":"122.339197ms","start":"2026-05-05T11:00:55.254839Z","end":"2026-05-05T11:00:55.377178Z","steps":["trace[1469516740] 'agreement among raft nodes before linearized reading' (duration: 122.183863ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:01.194713Z","caller":"traceutil/trace.go:171","msg":"trace[644802826] linearizableReadLoop","detail":"{readStateIndex:19690; appliedIndex:19689; }","duration":"143.362019ms","start":"2026-05-05T11:01:01.051318Z","end":"2026-05-05T11:01:01.19468Z","steps":["trace[644802826] 'read index received' (duration: 96.700136ms)","trace[644802826] 'applied index is now lower than readState.Index' (duration: 46.660123ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:01.194813Z","caller":"traceutil/trace.go:171","msg":"trace[401007299] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"144.536777ms","start":"2026-05-05T11:01:01.050227Z","end":"2026-05-05T11:01:01.194763Z","steps":["trace[401007299] 'process raft request' (duration: 97.819333ms)","trace[401007299] 'compare' (duration: 46.444057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:01:01.194961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.631725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T11:01:01.195003Z","caller":"traceutil/trace.go:171","msg":"trace[768437615] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:19003; }","duration":"143.714077ms","start":"2026-05-05T11:01:01.051279Z","end":"2026-05-05T11:01:01.194993Z","steps":["trace[768437615] 'agreement among raft nodes before linearized reading' (duration: 143.519372ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:01.614944Z","caller":"traceutil/trace.go:171","msg":"trace[1092653956] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"111.99044ms","start":"2026-05-05T11:01:01.502908Z","end":"2026-05-05T11:01:01.614898Z","steps":["trace[1092653956] 'process raft request' (duration: 45.911345ms)","trace[1092653956] 'compare' (duration: 65.680966ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:03.80445Z","caller":"traceutil/trace.go:171","msg":"trace[1158715840] transaction","detail":"{read_only:false; response_revision:19014; number_of_response:1; }","duration":"184.321254ms","start":"2026-05-05T11:01:03.620105Z","end":"2026-05-05T11:01:03.804426Z","steps":["trace[1158715840] 'process raft request' (duration: 184.15184ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:03.832939Z","caller":"traceutil/trace.go:171","msg":"trace[1884353019] transaction","detail":"{read_only:false; response_revision:19015; number_of_response:1; }","duration":"210.378015ms","start":"2026-05-05T11:01:03.622538Z","end":"2026-05-05T11:01:03.832916Z","steps":["trace[1884353019] 'process raft request' (duration: 204.988078ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:03.832933Z","caller":"traceutil/trace.go:171","msg":"trace[224450749] linearizableReadLoop","detail":"{readStateIndex:19703; appliedIndex:19700; }","duration":"130.154573ms","start":"2026-05-05T11:01:03.702749Z","end":"2026-05-05T11:01:03.832903Z","steps":["trace[224450749] 'read index received' (duration: 101.571632ms)","trace[224450749] 'applied index is now lower than readState.Index' (duration: 28.581551ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:03.833048Z","caller":"traceutil/trace.go:171","msg":"trace[172966446] transaction","detail":"{read_only:false; response_revision:19016; number_of_response:1; }","duration":"158.232652ms","start":"2026-05-05T11:01:03.674805Z","end":"2026-05-05T11:01:03.833038Z","steps":["trace[172966446] 'process raft request' (duration: 158.046408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:01:03.83321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.44743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T11:01:03.833297Z","caller":"traceutil/trace.go:171","msg":"trace[1432093953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19016; }","duration":"130.586554ms","start":"2026-05-05T11:01:03.702696Z","end":"2026-05-05T11:01:03.833282Z","steps":["trace[1432093953] 'agreement among raft nodes before linearized reading' (duration: 130.387848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:01:04.081139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.17009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T11:01:04.081477Z","caller":"traceutil/trace.go:171","msg":"trace[735774212] linearizableReadLoop","detail":"{readStateIndex:19705; appliedIndex:19704; }","duration":"145.261164ms","start":"2026-05-05T11:01:03.936193Z","end":"2026-05-05T11:01:04.081454Z","steps":["trace[735774212] 'read index received' (duration: 20.612163ms)","trace[735774212] 'applied index is now lower than readState.Index' (duration: 124.647051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:01:04.081658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.472489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bnjn4\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-05-05T11:01:04.081695Z","caller":"traceutil/trace.go:171","msg":"trace[1251284597] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bnjn4; range_end:; response_count:1; response_revision:19018; }","duration":"145.553821ms","start":"2026-05-05T11:01:03.936129Z","end":"2026-05-05T11:01:04.081683Z","steps":["trace[1251284597] 'agreement among raft nodes before linearized reading' (duration: 145.392387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:04.081707Z","caller":"traceutil/trace.go:171","msg":"trace[1381907918] transaction","detail":"{read_only:false; response_revision:19018; number_of_response:1; }","duration":"159.133465ms","start":"2026-05-05T11:01:03.922515Z","end":"2026-05-05T11:01:04.081648Z","steps":["trace[1381907918] 'process raft request' (duration: 34.35192ms)","trace[1381907918] 'compare' (duration: 123.839513ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:04.419408Z","caller":"traceutil/trace.go:171","msg":"trace[195610666] transaction","detail":"{read_only:false; response_revision:19019; number_of_response:1; }","duration":"114.587122ms","start":"2026-05-05T11:01:04.304787Z","end":"2026-05-05T11:01:04.419375Z","steps":["trace[195610666] 'process raft request' (duration: 114.409058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:04.821152Z","caller":"traceutil/trace.go:171","msg":"trace[320934795] transaction","detail":"{read_only:false; response_revision:19021; number_of_response:1; }","duration":"126.836514ms","start":"2026-05-05T11:01:04.694286Z","end":"2026-05-05T11:01:04.821122Z","steps":["trace[320934795] 'process raft request' (duration: 44.323487ms)","trace[320934795] 'compare' (duration: 82.378384ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:08.814835Z","caller":"traceutil/trace.go:171","msg":"trace[497285599] transaction","detail":"{read_only:false; response_revision:19041; number_of_response:1; }","duration":"152.080075ms","start":"2026-05-05T11:01:08.66273Z","end":"2026-05-05T11:01:08.81481Z","steps":["trace[497285599] 'process raft request' (duration: 151.917541ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:14.324223Z","caller":"traceutil/trace.go:171","msg":"trace[279767939] transaction","detail":"{read_only:false; response_revision:19068; number_of_response:1; }","duration":"116.597279ms","start":"2026-05-05T11:01:14.207604Z","end":"2026-05-05T11:01:14.324201Z","steps":["trace[279767939] 'process raft request' (duration: 112.889311ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:14.459093Z","caller":"traceutil/trace.go:171","msg":"trace[752282983] linearizableReadLoop","detail":"{readStateIndex:19759; appliedIndex:19757; }","duration":"128.994905ms","start":"2026-05-05T11:01:14.330074Z","end":"2026-05-05T11:01:14.459068Z","steps":["trace[752282983] 'read index received' (duration: 80.604511ms)","trace[752282983] 'applied index is now lower than readState.Index' (duration: 48.389124ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:01:14.459269Z","caller":"traceutil/trace.go:171","msg":"trace[1974954828] transaction","detail":"{read_only:false; response_revision:19070; number_of_response:1; }","duration":"129.654362ms","start":"2026-05-05T11:01:14.329565Z","end":"2026-05-05T11:01:14.459219Z","steps":["trace[1974954828] 'process raft request' (duration: 107.671298ms)","trace[1974954828] 'compare' (duration: 21.698017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:01:14.45976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.673571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582569"} {"level":"info","ts":"2026-05-05T11:01:14.459807Z","caller":"traceutil/trace.go:171","msg":"trace[1470053623] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:19070; }","duration":"129.783244ms","start":"2026-05-05T11:01:14.330014Z","end":"2026-05-05T11:01:14.459797Z","steps":["trace[1470053623] 'agreement among raft nodes before linearized reading' (duration: 129.160179ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:15.505083Z","caller":"traceutil/trace.go:171","msg":"trace[199716235] transaction","detail":"{read_only:false; response_revision:19074; number_of_response:1; }","duration":"113.547298ms","start":"2026-05-05T11:01:15.391496Z","end":"2026-05-05T11:01:15.505043Z","steps":["trace[199716235] 'process raft request' (duration: 113.330263ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:01:47.828453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17720} {"level":"info","ts":"2026-05-05T11:01:47.89852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17720,"took":"67.532076ms","hash":2626100320,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":20549632,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T11:01:47.898594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2626100320,"revision":17720,"compact-revision":16196} {"level":"info","ts":"2026-05-05T11:02:00.809496Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T11:02:00.814524Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T11:02:00.814906Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T11:02:31.169557Z","caller":"traceutil/trace.go:171","msg":"trace[141254767] transaction","detail":"{read_only:false; response_revision:19458; number_of_response:1; }","duration":"166.653293ms","start":"2026-05-05T11:02:31.002873Z","end":"2026-05-05T11:02:31.169527Z","steps":["trace[141254767] 'process raft request' (duration: 166.606392ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:02:31.16981Z","caller":"traceutil/trace.go:171","msg":"trace[528632505] transaction","detail":"{read_only:false; response_revision:19456; number_of_response:1; }","duration":"181.253787ms","start":"2026-05-05T11:02:30.988528Z","end":"2026-05-05T11:02:31.169782Z","steps":["trace[528632505] 'process raft request' (duration: 171.423109ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:02:31.169986Z","caller":"traceutil/trace.go:171","msg":"trace[1926820177] transaction","detail":"{read_only:false; response_revision:19457; number_of_response:1; }","duration":"171.143482ms","start":"2026-05-05T11:02:30.998834Z","end":"2026-05-05T11:02:31.169977Z","steps":["trace[1926820177] 'process raft request' (duration: 170.59225ms)"],"step_count":1}